Closed Bug 1640363 Opened 5 years ago Closed 5 years ago

Intermittent testing/marionette/harness/marionette_harness/tests/unit/test_unhandled_prompt_behavior.py TestUnhandledPromptBehavior.test_accept_and_notify_prompt | AssertionError: UnexpectedAlertOpen not raised

Categories

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

defect

Tracking

(Not tracked)

RESOLVED DUPLICATE of bug 1631112

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: assertion, intermittent-failure)

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


[task 2020-05-22T23:33:12.983Z] 23:33:12 INFO - JavaScript error: resource://gre/modules/Prompter.jsm, line 1178: NS_ERROR_NOT_AVAILABLE: prompt aborted by user
[task 2020-05-22T23:33:12.983Z] 23:33:12 INFO - 1590190392979 Marionette DEBUG 42 -> [0,3,"WebDriver:GetTitle",{}]
[task 2020-05-22T23:33:12.983Z] 23:33:12 INFO - 1590190392979 Marionette DEBUG 42 <- [1,3,null,{"value":"Marionette Test"}]
[task 2020-05-22T23:33:12.984Z] 23:33:12 INFO - 1590190392980 Marionette DEBUG 42 -> [0,4,"Marionette:GetContext",{}]
[task 2020-05-22T23:33:12.985Z] 23:33:12 INFO - 1590190392980 Marionette DEBUG 42 <- [1,4,null,{"value":"content"}]
[task 2020-05-22T23:33:12.986Z] 23:33:12 INFO - 1590190392981 Marionette DEBUG 42 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2020-05-22T23:33:12.986Z] 23:33:12 INFO - 1590190392981 Marionette DEBUG 42 <- [1,5,null,{"value":null}]
[task 2020-05-22T23:33:12.986Z] 23:33:12 INFO - 1590190392982 Marionette DEBUG 42 -> [0,6,"WebDriver:TakeScreenshot",{"full":true,"hash":false,"id":null,"scroll":true}]
[task 2020-05-22T23:33:13.085Z] 23:33:13 INFO - 1590190393071 Marionette DEBUG 42 <- [1,6,null,{"value":"iVBORw0KGgoAAAANSUhEUgAABQAAAAQHCAYAAACjlsT7AAAgAElEQVR4nOzd/XfcdZ33cX7cc7nXb/0L+oPn7DlX0jQtgqFqaSgRLWV3S ... IAAADAmAAEAAAAgDEBCAAAAABjAhAAAAAAxgQgAAAAAIwJQAAAAAAYE4AAAAAAMCYAAQAAAGBMAAIAAADAmAAEAAAAgLEATErvdmnNL0AAAAAASUVORK5CYII="}]
[task 2020-05-22T23:33:13.086Z] 23:33:13 INFO - 1590190393074 Marionette DEBUG 42 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2020-05-22T23:33:13.086Z] 23:33:13 INFO - 1590190393074 Marionette DEBUG 42 <- [1,7,null,{"value":null}]
[task 2020-05-22T23:33:13.086Z] 23:33:13 INFO - 1590190393075 Marionette DEBUG 42 -> [0,8,"Marionette:GetContext",{}]
[task 2020-05-22T23:33:13.086Z] 23:33:13 INFO - 1590190393075 Marionette DEBUG 42 <- [1,8,null,{"value":"content"}]
[task 2020-05-22T23:33:13.086Z] 23:33:13 INFO - 1590190393076 Marionette DEBUG 42 -> [0,9,"Marionette:SetContext",{"value":"content"}]
[task 2020-05-22T23:33:13.086Z] 23:33:13 INFO - 1590190393076 Marionette DEBUG 42 <- [1,9,null,{"value":null}]
[task 2020-05-22T23:33:13.086Z] 23:33:13 INFO - 1590190393076 Marionette DEBUG 42 -> [0,10,"WebDriver:GetPageSource",{}]
[task 2020-05-22T23:33:13.086Z] 23:33:13 INFO - 1590190393078 Marionette DEBUG 42 <- [1,10,null,{"value":"<html><head>\n<title>Marionette Test</title>\n</head>\n<body>\n\n \n</body></html>"}]
[task 2020-05-22T23:33:13.086Z] 23:33:13 INFO - 1590190393079 Marionette DEBUG 42 -> [0,11,"Marionette:SetContext",{"value":"content"}]
[task 2020-05-22T23:33:13.086Z] 23:33:13 INFO - 1590190393079 Marionette DEBUG 42 <- [1,11,null,{"value":null}]
[task 2020-05-22T23:33:13.086Z] 23:33:13 ERROR - TEST-UNEXPECTED-FAIL | testing/marionette/harness/marionette_harness/tests/unit/test_unhandled_prompt_behavior.py TestUnhandledPromptBehavior.test_accept_and_notify_prompt | AssertionError: UnexpectedAlertOpen not raised
[task 2020-05-22T23:33:13.086Z] 23:33:13 INFO - Traceback (most recent call last):
[task 2020-05-22T23:33:13.086Z] 23:33:13 INFO - File "Z:\task_1590188956\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
[task 2020-05-22T23:33:13.087Z] 23:33:13 INFO - testMethod()
[task 2020-05-22T23:33:13.087Z] 23:33:13 INFO - File "Z:\task_1590188956\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 33, in wrapper
[task 2020-05-22T23:33:13.087Z] 23:33:13 INFO - return func(self, *args, **kwargs)
[task 2020-05-22T23:33:13.087Z] 23:33:13 INFO - File "Z:\task_1590188956\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_unhandled_prompt_behavior.py", line 80, in test_accept_and_notify
[task 2020-05-22T23:33:13.087Z] 23:33:13 INFO - self.perform_user_prompt_check(prompt_type, "foo {}".format(prompt_type), result)
[task 2020-05-22T23:33:13.087Z] 23:33:13 INFO - File "Z:\task_1590188956\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_unhandled_prompt_behavior.py", line 49, in perform_user_prompt_check
[task 2020-05-22T23:33:13.087Z] 23:33:13 INFO - self.marionette.title
[task 2020-05-22T23:33:13.087Z] 23:33:13 INFO - TEST-INFO took 151ms
[task 2020-05-22T23:33:13.087Z] 23:33:13 INFO - 1590190393081 Marionette DEBUG 42 -> [0,12,"WebDriver:DismissAlert",{}]
[task 2020-05-22T23:33:13.087Z] 23:33:13 INFO - 1590190393081 Marionette DEBUG 42 <- [1,12,{"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-05-22T23:33:13.088Z] 23:33:13 INFO - 1590190393082 Marionette DEBUG 42 -> [0,13,"WebDriver:DeleteSession",{}]
[task 2020-05-22T23:33:13.088Z] 23:33:13 INFO - 1590190393083 Marionette DEBUG 42 <- [1,13,null,{"value":null}]
[task 2020-05-22T23:33:13.089Z] 23:33:13 INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_unhandled_prompt_behavior.py TestUnhandledPromptBehavior.test_accept_confirm
[task 2020-05-22T23:33:13.092Z] 23:33:13 INFO - 1590190393086 Marionette DEBUG Closed connection 42
[task 2020-05-22T23:33:13.092Z] 23:33:13 INFO - 1590190393087 Marionette DEBUG Accepted connection 43 from 127.0.0.1:52173
[task 2020-05-22T23:33:13.095Z] 23:33:13 INFO - 1590190393088 Marionette DEBUG 43 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2020-05-22T23:33:13.095Z] 23:33:13 INFO - 1590190393090 Marionette TRACE [4] Frame script loaded
[task 2020-05-22T23:33:13.095Z] 23:33:13 INFO - 1590190393090 Marionette TRACE [4] Frame script registered
[task 2020-05-22T23:33:13.095Z] 23:33:13 INFO - 1590190393090 Marionette DEBUG 43 <- [1,1,null,{"sessionId":"9a36e0da-97b9-461f-ad16-a4b1cd19fd37","capabilities":{"browserName":"firefox","browserVersion":"77.0" ... p\tmpqjg_ms.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2020-05-22T23:33:13.095Z] 23:33:13 INFO - 1590190393091 Marionette DEBUG 43 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2020-05-22T23:33:13.095Z] 23:33:13 INFO - 1590190393092 Marionette DEBUG 43 <- [1,2,null,{"value":null}]
[task 2020-05-22T23:33:13.098Z] 23:33:13 INFO - 1590190393092 Marionette DEBUG 43 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2020-05-22T23:33:13.098Z] 23:33:13 INFO - 1590190393092 Marionette DEBUG 43 <- [1,3,null,{"value":null}]
[task 2020-05-22T23:33:13.098Z] 23:33:13 INFO - 1590190393093 Marionette DEBUG 43 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2020-05-22T23:33:13.099Z] 23:33:13 INFO - 1590190393093 Marionette DEBUG 43 <- [1,4,null,{"value":null}]
[task 2020-05-22T23:33:13.099Z] 23:33:13 INFO - 1590190393094 Marionette DEBUG 43 -> [0,5,"WebDriver:DeleteSession",{}]
[task 2020-05-22T23:33:13.099Z] 23:33:13 INFO - 1590190393095 Marionette DEBUG 43 <- [1,5,null,{"value":null}]
[task 2020-05-22T23:33:13.100Z] 23:33:13 INFO - 1590190393097 Marionette DEBUG Closed connection 43
[task 2020-05-22T23:33:13.101Z] 23:33:13 INFO - 1590190393097 Marionette DEBUG Accepted connection 44 from 127.0.0.1:52174
[task 2020-05-22T23:33:13.102Z] 23:33:13 INFO - 1590190393098 Marionette DEBUG 44 -> [0,1,"WebDriver:NewSession",{"unhandledPromptBehavior":"accept"}]
[task 2020-05-22T23:33:13.102Z] 23:33:13 INFO - 1590190393099 Marionette TRACE [4] Frame script loaded
[task 2020-05-22T23:33:13.103Z] 23:33:13 INFO - 1590190393100 Marionette TRACE [4] Frame script registered
[task 2020-05-22T23:33:13.106Z] 23:33:13 INFO - 1590190393100 Marionette DEBUG 44 <- [1,1,null,{"sessionId":"128db880-9de6-4f46-8e2b-a343a46f2478","capabilities":{"browserName":"firefox","browserVersion":"77.0" ... p\tmpqjg_ms.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2020-05-22T23:33:13.106Z] 23:33:13 INFO - 1590190393101 Marionette DEBUG 44 -> [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-05-22T23:33:13.113Z] 23:33:13 INFO - 1590190393108 Marionette TRACE Received observer notification tabmodal-dialog-loaded
[task 2020-05-22T23:33:13.113Z] 23:33:13 INFO - 1590190393109 Marionette DEBUG 44 <- [1,2,null,{"value":null}]
[task 2020-05-22T23:33:13.116Z] 23:33:13 INFO - 1590190393111 Marionette DEBUG 44 -> [0,3,"WebDriver:GetTitle",{}]
[task 2020-05-22T23:33:13.116Z] 23:33:13 INFO - 1590190393112 Marionette TRACE Received event DOMModalDialogClosed
[task 2020-05-22T23:33:13.116Z] 23:33:13 INFO - 1590190393112 Marionette TRACE Received DOM event DOMModalDialogClosed for [object XULFrameElement]
[task 2020-05-22T23:33:13.131Z] 23:33:13 INFO - 1590190393117 Marionette DEBUG 44 <- [1,3,null,{"value":"Marionette Test"}]
[task 2020-05-22T23:33:13.131Z] 23:33:13 INFO - 1590190393119 Marionette DEBUG 44 -> [0,4,"WebDriver:GetAlertText",{}]
[task 2020-05-22T23:33:13.131Z] 23:33:13 INFO - 1590190393119 Marionette DEBUG 44 <- [1,4,{"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-05-22T23:33:13.131Z] 23:33:13 INFO - 1590190393121 Marionette DEBUG 44 -> [0,5,"WebDriver:ExecuteScript",{"script":"return window.return_value","newSandbox":false,"args":[],"filename":"Z:\task_15901 ... ing\marionette\harness\marionette_harness\tests\unit\test_unhandled_prompt_behavior.py","sandbox":"default","line":64}]
[task 2020-05-22T23:33:13.131Z] 23:33:13 INFO - 1590190393123 Marionette DEBUG 44 <- [1,5,null,{"value":true}]
[task 2020-05-22T23:33:13.131Z] 23:33:13 INFO - 1590190393124 Marionette DEBUG 44 -> [0,6,"WebDriver:DismissAlert",{}]
[task 2020-05-22T23:33:13.131Z] 23:33:13 INFO - 1590190393124 Marionette DEBUG 44 <- [1,6,{"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-05-22T23:33:13.131Z] 23:33:13 INFO - 1590190393125 Marionette DEBUG 44 -> [0,7,"WebDriver:DeleteSession",{}]
[task 2020-05-22T23:33:13.132Z] 23:33:13 INFO - 1590190393125 Marionette DEBUG 44 <- [1,7,null,{"value":null}]
[task 2020-05-22T23:33:13.132Z] 23:33:13 INFO - 1590190393126 Marionette DEBUG Closed connection 44
[task 2020-05-22T23:33:13.132Z] 23:33:13 INFO - TEST-PASS | testing/marionette/harness/marionette_harness/tests/unit/test_unhandled_prompt_behavior.py TestUnhandledPromptBehavior.test_accept_confirm | took 42ms

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → DUPLICATE
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.