Closed Bug 1632371 Opened 4 years ago Closed 4 years ago

Intermittent testing/marionette/harness/marionette_harness/tests/unit/test_unhandled_prompt_behavior.py TestUnhandledPromptBehavior.test_ignore_confirm | NoAlertPresentException:

Categories

(Testing :: Marionette Client and Harness, defect, P5)

Product:
Testing
Component:
Marionette Client and Harness
Version:
Version 3
Type:
defect

Tracking

(firefox77 wontfix, firefox78 fixed)

Status:
RESOLVED FIXED
Milestone:
mozilla78
Tracking Flags:
Tracking Status
firefox77 --- wontfix
firefox78 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: pbz)

References

Details

(Keywords: intermittent-failure)

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

[task 2020-04-23T00:22:16.609Z] 00:22:16 INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_unhandled_prompt_behavior.py TestUnhandledPromptBehavior.test_ignore_confirm
[task 2020-04-23T00:22:16.614Z] 00:22:16 INFO - 1587601336610 Marionette DEBUG Accepted connection 65 from 127.0.0.1:53049
[task 2020-04-23T00:22:16.614Z] 00:22:16 INFO - 1587601336611 Marionette DEBUG 65 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2020-04-23T00:22:16.624Z] 00:22:16 INFO - 1587601336620 Marionette TRACE [4] Frame script loaded
[task 2020-04-23T00:22:16.624Z] 00:22:16 INFO - 1587601336620 Marionette TRACE [4] Frame script registered
[task 2020-04-23T00:22:16.624Z] 00:22:16 INFO - 1587601336621 Marionette DEBUG 65 <- [1,1,null,{"sessionId":"bceea525-851b-4965-937c-3b698543be33","capabilities":{"browserName":"firefox","browserVersion":"77.0a ... p\tmpx9llzq.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2020-04-23T00:22:16.624Z] 00:22:16 INFO - 1587601336622 Marionette DEBUG 65 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2020-04-23T00:22:16.624Z] 00:22:16 INFO - 1587601336622 Marionette DEBUG 65 <- [1,2,null,{"value":null}]
[task 2020-04-23T00:22:16.626Z] 00:22:16 INFO - 1587601336623 Marionette DEBUG 65 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2020-04-23T00:22:16.626Z] 00:22:16 INFO - 1587601336624 Marionette DEBUG 65 <- [1,3,null,{"value":null}]
[task 2020-04-23T00:22:16.626Z] 00:22:16 INFO - 1587601336625 Marionette DEBUG 65 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2020-04-23T00:22:16.626Z] 00:22:16 INFO - 1587601336625 Marionette DEBUG 65 <- [1,4,null,{"value":null}]
[task 2020-04-23T00:22:16.628Z] 00:22:16 INFO - 1587601336626 Marionette DEBUG 65 -> [0,5,"WebDriver:DeleteSession",{}]
[task 2020-04-23T00:22:16.628Z] 00:22:16 INFO - 1587601336627 Marionette DEBUG 65 <- [1,5,null,{"value":null}]
[task 2020-04-23T00:22:16.630Z] 00:22:16 INFO - 1587601336628 Marionette DEBUG Closed connection 65
[task 2020-04-23T00:22:16.630Z] 00:22:16 INFO - 1587601336628 Marionette DEBUG Accepted connection 66 from 127.0.0.1:53050
[task 2020-04-23T00:22:16.632Z] 00:22:16 INFO - 1587601336630 Marionette DEBUG 66 -> [0,1,"WebDriver:NewSession",{"unhandledPromptBehavior":"ignore"}]
[task 2020-04-23T00:22:16.634Z] 00:22:16 INFO - 1587601336631 Marionette TRACE [4] Frame script loaded
[task 2020-04-23T00:22:16.634Z] 00:22:16 INFO - 1587601336631 Marionette TRACE [4] Frame script registered
[task 2020-04-23T00:22:16.634Z] 00:22:16 INFO - 1587601336632 Marionette DEBUG 66 <- [1,1,null,{"sessionId":"3f184b23-c948-45f4-8ca5-141afcbda720","capabilities":{"browserName":"firefox","browserVersion":"77.0a ... p\tmpx9llzq.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2020-04-23T00:22:16.636Z] 00:22:16 INFO - 1587601336634 Marionette DEBUG 66 -> [0,2,"WebDriver:ExecuteAsyncScript",{"scriptTimeout":null,"newSandbox":true,"args":["confirm","foo confirm"],"filename":"Z:\ ... .return_value = null;\n window.return_value = windowarguments[0];","sandbox":"default","line":45}]
[task 2020-04-23T00:22:16.645Z] 00:22:16 INFO - 1587601336640 Marionette TRACE Received observer notification tabmodal-dialog-loaded
[task 2020-04-23T00:22:16.645Z] 00:22:16 INFO - 1587601336640 Marionette DEBUG 66 <- [1,2,null,{"value":null}]
[task 2020-04-23T00:22:16.645Z] 00:22:16 INFO - 1587601336641 Marionette DEBUG 66 -> [0,3,"WebDriver:GetTitle",{}]
[task 2020-04-23T00:22:16.645Z] 00:22:16 INFO - 1587601336642 Marionette DEBUG 66 <- [1,3,{"error":"unexpected alert open","message":"Encountered unhandled user prompt dialog","stacktrace":"WebDriverError@chrom ... t@chrome://marionette/content/server.js:249:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20\n"},null]
[task 2020-04-23T00:22:16.645Z] 00:22:16 INFO - 1587601336643 Marionette DEBUG 66 -> [0,4,"WebDriver:GetAlertText",{}]
[task 2020-04-23T00:22:16.645Z] 00:22:16 INFO - 1587601336643 Marionette DEBUG 66 <- [1,4,null,{"value":"foo confirm"}]
[task 2020-04-23T00:22:16.645Z] 00:22:16 INFO - 1587601336644 Marionette TRACE Received event DOMModalDialogClosed
[task 2020-04-23T00:22:16.647Z] 00:22:16 INFO - 1587601336645 Marionette DEBUG 66 -> [0,5,"WebDriver:DismissAlert",{}]
[task 2020-04-23T00:22:16.647Z] 00:22:16 INFO - JavaScript error: resource://gre/modules/Prompter.jsm, line 1178: NS_ERROR_NOT_AVAILABLE: prompt ab1587601336646 Marionette DEBUG 66 <- [1,5,{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://marionette/content/error.js:175:5\nNoSuchAle ... t@chrome://marionette/content/server.js:249:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20\n"},null]
[task 2020-04-23T00:22:16.647Z] 00:22:16 INFO - orted by user
[task 2020-04-23T00:22:16.649Z] 00:22:16 INFO - 1587601336647 Marionette DEBUG 66 -> [0,6,"Marionette:GetContext",{}]
[task 2020-04-23T00:22:16.649Z] 00:22:16 INFO - 1587601336648 Marionette DEBUG 66 <- [1,6,null,{"value":"content"}]
[task 2020-04-23T00:22:16.651Z] 00:22:16 INFO - 1587601336648 Marionette DEBUG 66 -> [0,7,"Marionette:SetContext",{"value":"chrome"}]
[task 2020-04-23T00:22:16.651Z] 00:22:16 INFO - 1587601336649 Marionette DEBUG 66 <- [1,7,null,{"value":null}]
[task 2020-04-23T00:22:16.651Z] 00:22:16 INFO - 1587601336649 Marionette DEBUG 66 -> [0,8,"WebDriver:TakeScreenshot",{"full":true,"hash":false,"id":null,"scroll":true}]
[task 2020-04-23T00:22:16.711Z] 00:22:16 INFO - 1587601336701 Marionette DEBUG 66 <- [1,8,null,{"value":"iVBORw0KGgoAAAANSUhEUgAABQAAAAQHCAYAAACjlsT7AAAgAElEQVR4nOzdf4zU933n8f2zuvxxp/5RVf3nLKX/WISrXMmW4ijq8UeVl ... IAAADAmAAEAAAAgDEBCAAAAABjAhAAAAAAxgQgAAAAAIwJQAAAAAAYE4AAAAAAMCYAAQAAAGBMAAIAAADAmAAEAAAAgLEA8fND1kZp4+UAAAAASUVORK5CYII="}]
[task 2020-04-23T00:22:16.711Z] 00:22:16 INFO - 1587601336703 Marionette DEBUG 66 -> [0,9,"Marionette:SetContext",{"value":"content"}]
[task 2020-04-23T00:22:16.712Z] 00:22:16 INFO - 1587601336703 Marionette DEBUG 66 <- [1,9,null,{"value":null}]
[task 2020-04-23T00:22:16.712Z] 00:22:16 INFO - 1587601336704 Marionette DEBUG 66 -> [0,10,"Marionette:GetContext",{}]
[task 2020-04-23T00:22:16.713Z] 00:22:16 INFO - 1587601336704 Marionette DEBUG 66 <- [1,10,null,{"value":"content"}]
[task 2020-04-23T00:22:16.713Z] 00:22:16 INFO - 1587601336705 Marionette DEBUG 66 -> [0,11,"Marionette:SetContext",{"value":"content"}]
[task 2020-04-23T00:22:16.714Z] 00:22:16 INFO - 1587601336706 Marionette DEBUG 66 <- [1,11,null,{"value":null}]
[task 2020-04-23T00:22:16.714Z] 00:22:16 INFO - 1587601336706 Marionette DEBUG 66 -> [0,12,"WebDriver:GetPageSource",{}]
[task 2020-04-23T00:22:16.715Z] 00:22:16 INFO - 1587601336708 Marionette DEBUG 66 <- [1,12,null,{"value":"<html><head>\n<title>Marionette Test</title>\n</head>\n<body>\n\n \n</body></html>"}]
[task 2020-04-23T00:22:16.715Z] 00:22:16 INFO - 1587601336709 Marionette DEBUG 66 -> [0,13,"Marionette:SetContext",{"value":"content"}]
[task 2020-04-23T00:22:16.716Z] 00:22:16 INFO - 1587601336709 Marionette DEBUG 66 <- [1,13,null,{"value":null}]
[task 2020-04-23T00:22:16.716Z] 00:22:16 ERROR - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_unhandled_prompt_behavior.py TestUnhandledPromptBehavior.test_ignore_confirm | NoAlertPresentException:
[task 2020-04-23T00:22:16.716Z] 00:22:16 INFO - stacktrace:
[task 2020-04-23T00:22:16.716Z] 00:22:16 INFO - WebDriverError@chrome://marionette/content/error.js:175:5
[task 2020-04-23T00:22:16.716Z] 00:22:16 INFO - NoSuchAlertError@chrome://marionette/content/error.js:376:5
[task 2020-04-23T00:22:16.717Z] 00:22:16 INFO - GeckoDriver.prototype._checkIfAlertIsPresent@chrome://marionette/content/driver.js:3388:11
[task 2020-04-23T00:22:16.717Z] 00:22:16 INFO - GeckoDriver.prototype.dismissDialog@chrome://marionette/content/driver.js:3301:8
[task 2020-04-23T00:22:16.717Z] 00:22:16 INFO - despatch@chrome://marionette/content/server.js:305:40
[task 2020-04-23T00:22:16.717Z] 00:22:16 INFO - execute@chrome://marionette/content/server.js:275:16
[task 2020-04-23T00:22:16.717Z] 00:22:16 INFO - onPacket/<@chrome://marionette/content/server.js:248:20
[task 2020-04-23T00:22:16.717Z] 00:22:16 INFO - onPacket@chrome://marionette/content/server.js:249:9
[task 2020-04-23T00:22:16.717Z] 00:22:16 INFO - _onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20
[task 2020-04-23T00:22:16.717Z] 00:22:16 INFO - Traceback (most recent call last):
[task 2020-04-23T00:22:16.717Z] 00:22:16 INFO - File "Z:\task_1587598943\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
[task 2020-04-23T00:22:16.717Z] 00:22:16 INFO - testMethod()
[task 2020-04-23T00:22:16.717Z] 00:22:16 INFO - File "Z:\task_1587598943\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 33, in wrapper
[task 2020-04-23T00:22:16.717Z] 00:22:16 INFO - return func(self, *args, **kwargs)
[task 2020-04-23T00:22:16.717Z] 00:22:16 INFO - File "Z:\task_1587598943\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_unhandled_prompt_behavior.py", line 103, in test_ignore
[task 2020-04-23T00:22:16.717Z] 00:22:16 INFO - expected_close=False)
[task 2020-04-23T00:22:16.718Z] 00:22:16 INFO - File "Z:\task_1587598943\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_unhandled_prompt_behavior.py", line 60, in perform_user_prompt_check
[task 2020-04-23T00:22:16.718Z] 00:22:16 INFO - alert.dismiss()
[task 2020-04-23T00:22:16.718Z] 00:22:16 INFO - File "Z:\task_1587598943\build\venv\lib\site-packages\marionette_driver\marionette.py", line 377, in dismiss
[task 2020-04-23T00:22:16.718Z] 00:22:16 INFO - self.marionette._send_message("WebDriver:DismissAlert")
[task 2020-04-23T00:22:16.718Z] 00:22:16 INFO - File "Z:\task_1587598943\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
[task 2020-04-23T00:22:16.718Z] 00:22:16 INFO - return func(*args, **kwargs)
[task 2020-04-23T00:22:16.718Z] 00:22:16 INFO - File "Z:\task_1587598943\build\venv\lib\site-packages\marionette_driver\marionette.py", line 597, in _send_message
[task 2020-04-23T00:22:16.718Z] 00:22:16 INFO - self._handle_error(err)
[task 2020-04-23T00:22:16.718Z] 00:22:16 INFO - File "Z:\task_1587598943\build\venv\lib\site-packages\marionette_driver\marionette.py", line 617, in _handle_error
[task 2020-04-23T00:22:16.718Z] 00:22:16 INFO - raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2020-04-23T00:22:16.718Z] 00:22:16 INFO - TEST-INFO took 101ms
[task 2020-04-23T00:22:16.718Z] 00:22:16 INFO - 1587601336712 Marionette DEBUG 66 -> [0,14,"WebDriver:DismissAlert",{}]
[task 2020-04-23T00:22:16.718Z] 00:22:16 INFO - 1587601336712 Marionette DEBUG 66 <- [1,14,{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://marionette/content/error.js:175:5\nNoSuchAl ... t@chrome://marionette/content/server.js:249:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20\n"},null]
[task 2020-04-23T00:22:16.719Z] 00:22:16 INFO - 1587601336713 Marionette DEBUG 66 -> [0,15,"WebDriver:DeleteSession",{}]
[task 2020-04-23T00:22:16.719Z] 00:22:16 INFO - 1587601336714 Marionette DEBUG 66 <- [1,15,null,{"value":null}]

This depends on a fix for bug 1631362.

Depends on: 1631362

No more failures since the patch on bug 1631362 got landed.

Assignee: nobody → pbz
Status: NEW → RESOLVED
Closed: 4 years ago
status-firefox77: --- → affected
status-firefox78: --- → fixed
Resolution: --- → FIXED
Target Milestone: --- → mozilla78
Product: Testing → Remote Protocol
Moving bug to Testing::Marionette Client and Harness component per bug 1815831.
Component: Marionette → Marionette Client and Harness
Product: Remote Protocol → Testing
You need to log in before you can comment on or make changes to this bug.