Closed
Bug 1749111
Opened 2 years ago
Closed 2 years ago
Intermittent /webdriver/tests/close_window/user_prompts.py | <smth> - webdriver.error.TimeoutException: timeout (500): Timed out after 15.0 seconds with message: No user prompt with text '<smth>' detected
Categories
(Remote Protocol :: Marionette, defect, P5)
Remote Protocol
Marionette
Tracking
(firefox-esr91 unaffected, firefox96 unaffected, firefox97 fixed, firefox98 fixed)
RESOLVED
FIXED
98 Branch
| Tracking | Status | |
|---|---|---|
| firefox-esr91 | --- | unaffected |
| firefox96 | --- | unaffected |
| firefox97 | --- | fixed |
| firefox98 | --- | fixed |
People
(Reporter: intermittent-bug-filer, Assigned: whimboo)
References
(Regression)
Details
(Keywords: intermittent-failure, regression)
Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=363462965&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/PnRQvziHQUeVwDS6okpCoA/runs/0/artifacts/public/logs/live_backing.log
[task 2022-01-08T06:54:17.150Z] 06:54:17 INFO - TEST-PASS | /webdriver/tests/close_window/user_prompts.py | test_default[confirm-False]
[task 2022-01-08T06:54:17.150Z] 06:54:17 INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/close_window/user_prompts.py | test_default[prompt-None] - webdriver.error.TimeoutException: timeout (500): Timed out after 15.0 seconds with message: No user prompt with text 'prompt' detected
[task 2022-01-08T06:54:17.150Z] 06:54:17 INFO - check_user_prompt_closed_with_exception = <function check_user_prompt_closed_with_exception.<locals>.check_user_prompt_closed_with_exception at 0x000001C91BBF5A60>
[task 2022-01-08T06:54:17.150Z] 06:54:17 INFO - dialog_type = 'prompt', retval = None
[task 2022-01-08T06:54:17.150Z] 06:54:17 INFO -
[task 2022-01-08T06:54:17.150Z] 06:54:17 INFO - @pytest.mark.parametrize("dialog_type, retval", [
[task 2022-01-08T06:54:17.150Z] 06:54:17 INFO - ("alert", None),
[task 2022-01-08T06:54:17.150Z] 06:54:17 INFO - ("confirm", False),
[task 2022-01-08T06:54:17.151Z] 06:54:17 INFO - ("prompt", None),
[task 2022-01-08T06:54:17.151Z] 06:54:17 INFO - ])
[task 2022-01-08T06:54:17.151Z] 06:54:17 INFO - def test_default(check_user_prompt_closed_with_exception, dialog_type, retval):
[task 2022-01-08T06:54:17.151Z] 06:54:17 INFO - > check_user_prompt_closed_with_exception(dialog_type, retval)
[task 2022-01-08T06:54:17.151Z] 06:54:17 INFO -
[task 2022-01-08T06:54:17.151Z] 06:54:17 INFO - check_user_prompt_closed_with_exception = <function check_user_prompt_closed_with_exception.<locals>.check_user_prompt_closed_with_exception at 0x000001C91BBF5A60>
[task 2022-01-08T06:54:17.151Z] 06:54:17 INFO - dialog_type = 'prompt'
[task 2022-01-08T06:54:17.151Z] 06:54:17 INFO - retval = None
[task 2022-01-08T06:54:17.151Z] 06:54:17 INFO -
[task 2022-01-08T06:54:17.151Z] 06:54:17 INFO - tests\web-platform\tests\webdriver\tests\close_window\user_prompts.py:119:
[task 2022-01-08T06:54:17.151Z] 06:54:17 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2022-01-08T06:54:17.151Z] 06:54:17 INFO - tests\web-platform\tests\webdriver\tests\close_window\user_prompts.py:42: in check_user_prompt_closed_with_exception
[task 2022-01-08T06:54:17.151Z] 06:54:17 INFO - create_dialog(dialog_type, text=dialog_type)
[task 2022-01-08T06:54:17.151Z] 06:54:17 INFO - create_dialog = <function create_dialog.<locals>.create_dialog at 0x000001C91BBF5820>
[task 2022-01-08T06:54:17.151Z] 06:54:17 INFO - dialog_type = 'prompt'
[task 2022-01-08T06:54:17.152Z] 06:54:17 INFO - new_handle = '2261b443-4b79-40b0-888c-a17d005a6b75'
[task 2022-01-08T06:54:17.152Z] 06:54:17 INFO - retval = None
[task 2022-01-08T06:54:17.152Z] 06:54:17 INFO - session = <Session addd3bf1-2838-40e3-9447-bc304da72728>
[task 2022-01-08T06:54:17.152Z] 06:54:17 INFO - tests\web-platform\tests\webdriver\tests\support\fixtures_http.py:132: in create_dialog
[task 2022-01-08T06:54:17.152Z] 06:54:17 INFO - wait.until(lambda s: s.alert.text == text)
[task 2022-01-08T06:54:17.152Z] 06:54:17 INFO - dialog_type = 'prompt'
[task 2022-01-08T06:54:17.152Z] 06:54:17 INFO - session = <Session addd3bf1-2838-40e3-9447-bc304da72728>
[task 2022-01-08T06:54:17.152Z] 06:54:17 INFO - text = 'prompt'
[task 2022-01-08T06:54:17.152Z] 06:54:17 INFO - wait = <tests.support.sync.Poll object at 0x000001C91BC2A130>
[task 2022-01-08T06:54:17.152Z] 06:54:17 INFO - tests\web-platform\tests\webdriver\tests\support\sync.py:141: in until
[task 2022-01-08T06:54:17.152Z] 06:54:17 INFO - raise self.exc_cls(message=message).with_traceback(tb)
[task 2022-01-08T06:54:17.153Z] 06:54:17 INFO - _ = <NoSuchAlertException http_status=404>
[task 2022-01-08T06:54:17.153Z] 06:54:17 INFO - condition = <function create_dialog.<locals>.create_dialog.<locals>.<lambda> at 0x000001C91BB8D700>
[task 2022-01-08T06:54:17.153Z] 06:54:17 INFO - elapsed = 15.0
[task 2022-01-08T06:54:17.153Z] 06:54:17 INFO - end = 1641624856.8542259
[task 2022-01-08T06:54:17.153Z] 06:54:17 INFO - interval_new = 0.09999990463256836
[task 2022-01-08T06:54:17.153Z] 06:54:17 INFO - message = ("Timed out after 15.0 seconds with message: No user prompt with text 'prompt' "
[task 2022-01-08T06:54:17.153Z] 06:54:17 INFO - 'detected')
[task 2022-01-08T06:54:17.153Z] 06:54:17 INFO - next = 1641624856.8566353
[task 2022-01-08T06:54:17.153Z] 06:54:17 INFO - rv = None
[task 2022-01-08T06:54:17.153Z] 06:54:17 INFO - self = <tests.support.sync.Poll object at 0x000001C91BC2A130>
[task 2022-01-08T06:54:17.153Z] 06:54:17 INFO - start = 1641624841.8542259
[task 2022-01-08T06:54:17.153Z] 06:54:17 INFO - tb = <traceback object at 0x000001C91BC41140>
[task 2022-01-08T06:54:17.153Z] 06:54:17 INFO - tests\web-platform\tests\webdriver\tests\support\sync.py:117: in until
[task 2022-01-08T06:54:17.153Z] 06:54:17 INFO - rv = condition(self.session)
[task 2022-01-08T06:54:17.154Z] 06:54:17 INFO - _ = <NoSuchAlertException http_status=404>
[task 2022-01-08T06:54:17.154Z] 06:54:17 INFO - condition = <function create_dialog.<locals>.create_dialog.<locals>.<lambda> at 0x000001C91BB8D700>
[task 2022-01-08T06:54:17.154Z] 06:54:17 INFO - elapsed = 15.0
[task 2022-01-08T06:54:17.154Z] 06:54:17 INFO - end = 1641624856.8542259
[task 2022-01-08T06:54:17.154Z] 06:54:17 INFO - interval_new = 0.09999990463256836
[task 2022-01-08T06:54:17.154Z] 06:54:17 INFO - message = ("Timed out after 15.0 seconds with message: No user prompt with text 'prompt' "
[task 2022-01-08T06:54:17.154Z] 06:54:17 INFO - 'detected')
[task 2022-01-08T06:54:17.154Z] 06:54:17 INFO - next = 1641624856.8566353
[task 2022-01-08T06:54:17.154Z] 06:54:17 INFO - rv = None
[task 2022-01-08T06:54:17.154Z] 06:54:17 INFO - self = <tests.support.sync.Poll object at 0x000001C91BC2A130>
[task 2022-01-08T06:54:17.154Z] 06:54:17 INFO - start = 1641624841.8542259
[task 2022-01-08T06:54:17.154Z] 06:54:17 INFO - tb = <traceback object at 0x000001C91BC41140>
[task 2022-01-08T06:54:17.154Z] 06:54:17 INFO - tests\web-platform\tests\webdriver\tests\support\fixtures_http.py:132: in <lambda>
[task 2022-01-08T06:54:17.154Z] 06:54:17 INFO - wait.until(lambda s: s.alert.text == text)
[task 2022-01-08T06:54:17.154Z] 06:54:17 INFO - s = <Session addd3bf1-2838-40e3-9447-bc304da72728>
[task 2022-01-08T06:54:17.155Z] 06:54:17 INFO - text = 'prompt'
[task 2022-01-08T06:54:17.155Z] 06:54:17 INFO - tests\web-platform\tests\tools\webdriver\webdriver\client.py:20: in inner
[task 2022-01-08T06:54:17.155Z] 06:54:17 INFO - return func(self, *args, **kwargs)
[task 2022-01-08T06:54:17.155Z] 06:54:17 INFO - args = ()
[task 2022-01-08T06:54:17.155Z] 06:54:17 INFO - func = <function UserPrompt.text at 0x000001C91BAC4C10>
[task 2022-01-08T06:54:17.155Z] 06:54:17 INFO - kwargs = {}
[task 2022-01-08T06:54:17.155Z] 06:54:17 INFO - self = <webdriver.client.UserPrompt object at 0x000001C91BBC6910>
[task 2022-01-08T06:54:17.155Z] 06:54:17 INFO - session = <Session addd3bf1-2838-40e3-9447-bc304da72728>
[task 2022-01-08T06:54:17.155Z] 06:54:17 INFO - tests\web-platform\tests\tools\webdriver\webdriver\client.py:479: in text
[task 2022-01-08T06:54:17.155Z] 06:54:17 INFO - return self.session.send_session_command("GET", "alert/text")
[task 2022-01-08T06:54:17.155Z] 06:54:17 INFO - self = <webdriver.client.UserPrompt object at 0x000001C91BBC6910>
[task 2022-01-08T06:54:17.155Z] 06:54:17 INFO - tests\web-platform\tests\tools\webdriver\webdriver\client.py:659: in send_session_command
[task 2022-01-08T06:54:17.155Z] 06:54:17 INFO - return self.send_command(method, url, body, timeout)
[task 2022-01-08T06:54:17.155Z] 06:54:17 INFO - body = None
[task 2022-01-08T06:54:17.155Z] 06:54:17 INFO - method = 'GET'
[task 2022-01-08T06:54:17.155Z] 06:54:17 INFO - self = <Session addd3bf1-2838-40e3-9447-bc304da72728>
[task 2022-01-08T06:54:17.156Z] 06:54:17 INFO - timeout = None
[task 2022-01-08T06:54:17.156Z] 06:54:17 INFO - uri = 'alert/text'
[task 2022-01-08T06:54:17.156Z] 06:54:17 INFO - url = 'session/addd3bf1-2838-40e3-9447-bc304da72728/alert/text'
[task 2022-01-08T06:54:17.156Z] 06:54:17 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2022-01-08T06:54:17.156Z] 06:54:17 INFO -
[task 2022-01-08T06:54:17.156Z] 06:54:17 INFO - self = <Session addd3bf1-2838-40e3-9447-bc304da72728>, method = 'GET'
[task 2022-01-08T06:54:17.156Z] 06:54:17 INFO - url = 'session/addd3bf1-2838-40e3-9447-bc304da72728/alert/text', body = None
[task 2022-01-08T06:54:17.156Z] 06:54:17 INFO - timeout = None
|
||
Updated•2 years ago
|
Summary: Intermittent /webdriver/tests/close_window/user_prompts.py | test_default[prompt-None] - webdriver.error.TimeoutException: timeout (500): Timed out after 15.0 seconds with message: No user prompt with text 'prompt' detected → Intermittent /webdriver/tests/close_window/user_prompts.py | <smth> - webdriver.error.TimeoutException: timeout (500): Timed out after 15.0 seconds with message: No user prompt with text 'prompt' detected
| Comment hidden (Intermittent Failures Robot) |
|
Assignee | |
Comment 2•2 years ago
|
||
An expected alert that is going to be opened never appeared on the page. Maybe it's a regression from bug 1739369 given that this failure started around the same time. Here an excerpt:
https://treeherder.mozilla.org/logviewer?job_id=363462965&repo=autoland&lineNumber=9281-9299
[task 2022-01-08T06:54:01.871Z] 06:54:01 INFO - PID 6576 | 1641624841821 Marionette DEBUG 0 -> [0,62,"WebDriver:NewWindow",{"type":"tab"}]
[task 2022-01-08T06:54:01.872Z] 06:54:01 INFO - PID 6576 | 1641624841826 Marionette TRACE Received DOM event TabOpen for [object XULElement]
[task 2022-01-08T06:54:01.874Z] 06:54:01 INFO - PID 6576 | 1641624841840 RemoteAgent TRACE Initial navigation already completed
[task 2022-01-08T06:54:01.874Z] 06:54:01 INFO - PID 6576 | 1641624841841 Marionette DEBUG 0 <- [1,62,null,{"handle":"2261b443-4b79-40b0-888c-a17d005a6b75","type":"tab"}]
[task 2022-01-08T06:54:01.875Z] 06:54:01 INFO - PID 6576 | 1641624841843 webdriver::server DEBUG <- 200 OK {"value":{"handle":"2261b443-4b79-40b0-888c-a17d005a6b75","type":"tab"}}
[task 2022-01-08T06:54:01.876Z] 06:54:01 INFO - PID 6576 | 1641624841845 webdriver::server DEBUG -> POST /session/addd3bf1-2838-40e3-9447-bc304da72728/window {"handle": "2261b443-4b79-40b0-888c-a17d005a6b75"}
[task 2022-01-08T06:54:01.877Z] 06:54:01 INFO - PID 6576 | 1641624841845 Marionette DEBUG 0 -> [0,63,"WebDriver:SwitchToWindow",{"handle":"2261b443-4b79-40b0-888c-a17d005a6b75"}]
[task 2022-01-08T06:54:01.878Z] 06:54:01 INFO - PID 6576 | 1641624841848 Marionette TRACE Received DOM event TabSelect for [object XULElement]
[task 2022-01-08T06:54:01.879Z] 06:54:01 INFO - PID 6576 | 1641624841850 Marionette DEBUG 0 <- [1,63,null,{"value":null}]
[task 2022-01-08T06:54:01.880Z] 06:54:01 INFO - PID 6576 | 1641624841850 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-01-08T06:54:01.881Z] 06:54:01 INFO - PID 6576 | 1641624841852 webdriver::server DEBUG -> POST /session/addd3bf1-2838-40e3-9447-bc304da72728/execute/async {"script": "\n let dialog_type = arguments[0];\n let text = arguments[1];\n\n setTimeout(function() {\n if (dialog_type == 'prompt') {\n window.dialog_return_value = window[dialog_type](text, '');\n } else {\n window.dialog_return_value = window[dialog_type](text);\n }\n }, 0);\n ", "args": ["prompt", "prompt"]}
[task 2022-01-08T06:54:01.883Z] 06:54:01 INFO - PID 6576 | 1641624841852 Marionette DEBUG 0 -> [0,64,"WebDriver:ExecuteAsyncScript",{"args":["prompt","prompt"],"script":"\n let dialog_type = arguments[0];\n ... window.dialog_return_value = window[dialog_type](text);\n }\n }, 0);\n "}]
[task 2022-01-08T06:54:01.883Z] 06:54:01 INFO - PID 6576 | 1641624841854 Marionette DEBUG 0 <- [1,64,null,{"value":null}]
[task 2022-01-08T06:54:01.884Z] 06:54:01 INFO - PID 6576 | 1641624841854 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-01-08T06:54:01.885Z] 06:54:01 INFO - PID 6576 | 1641624841855 Marionette TRACE [53] MarionetteCommands actor created for window id 52
[task 2022-01-08T06:54:01.886Z] 06:54:01 INFO - PID 6576 | 1641624841855 webdriver::server DEBUG -> GET /session/addd3bf1-2838-40e3-9447-bc304da72728/alert/text
[task 2022-01-08T06:54:01.887Z] 06:54:01 INFO - PID 6576 | 1641624841858 Marionette DEBUG 0 -> [0,65,"WebDriver:GetAlertText",{}]
[task 2022-01-08T06:54:01.888Z] 06:54:01 INFO - PID 6576 | 1641624841858 Marionette DEBUG 0 <- [1,65,{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://remote/content/shared/webdriver/Errors.jsm: ... ote/content/marionette/server.js:253:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.js:500:20\n"},null]
[task 2022-01-08T06:54:01.890Z] 06:54:01 INFO - PID 6576 | 1641624841858 webdriver::server DEBUG <- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://remote/content/shared/webdriver/Errors.jsm:183:5\nNoSuchAlertError@chrome://remote/content/shared/webdriver/Errors.jsm:384:5\nGeckoDriver.prototype._checkIfAlertIsPresent@chrome://remote/content/marionette/driver.js:2527:11\nGeckoDriver.prototype.getTextFromDialog@chrome://remote/content/marionette/driver.js:2472:8\ndespatch@chrome://remote/content/marionette/server.js:306:40\nexecute@chrome://remote/content/marionette/server.js:279:16\nonPacket/<@chrome://remote/content/marionette/server.js:252:20\nonPacket@chrome://remote/content/marionette/server.js:253:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.js:500:20\n"}}
Keywords: regression
Regressed by: 1739369
|
|
Assignee | |
Updated•2 years ago
|
Summary: Intermittent /webdriver/tests/close_window/user_prompts.py | <smth> - webdriver.error.TimeoutException: timeout (500): Timed out after 15.0 seconds with message: No user prompt with text 'prompt' detected → Intermittent /webdriver/tests/close_window/user_prompts.py | <smth> - webdriver.error.TimeoutException: timeout (500): Timed out after 15.0 seconds with message: No user prompt with text '<smth>' detected
|
||
Comment 4•2 years ago
|
||
Set release status flags based on info from the regressing bug 1739369
status-firefox96:
--- → unaffected
status-firefox97:
--- → affected
status-firefox98:
--- → affected
status-firefox-esr91:
--- → unaffected
| Comment hidden (Intermittent Failures Robot) |
|
|
Assignee | |
Updated•2 years ago
|
Assignee: nobody → hskupin
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → FIXED
Target Milestone: --- → 98 Branch
|
||
Updated•2 years ago
|
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Updated•2 years ago
|
Has Regression Range: --- → yes
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 8•1 year ago
|
||
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.
Description
•