Closed Bug 1756942 Opened 3 years ago Closed 3 years ago

Intermittent /webdriver/tests/get_window_rect/user_prompts.py | test_ignore[capabilities0-alert] - webdriver.error.TimeoutException: timeout (500): Timed out after 15.1 seconds with message: No user prompt with text 'alert' detected

Tracking

(firefox-esr91 wontfix, firefox97 unaffected, firefox98 unaffected, firefox99 unaffected)

Status:

RESOLVED INCOMPLETE

Tracking Flags:

Tracking

Status

firefox-esr91

---

wontfix

firefox97

---

unaffected

firefox98

---

unaffected

firefox99

---

unaffected

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Treeherder Bug Filer

Reporter

Description

•

3 years ago

treeherder

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

[task 2022-02-24T06:42:02.557Z] 06:42:02     INFO - STDOUT: FAILED tests/web-platform/tests/webdriver/tests/get_window_rect/user_prompts.py::test_ignore[capabilities0-alert]
[task 2022-02-24T06:42:02.558Z] 06:42:02     INFO - STDOUT: ================== 1 failed, 17 passed, 2 warnings in 28.50s ===================
[task 2022-02-24T06:42:02.559Z] 06:42:02     INFO - PID 2053 | 1645684922451	webdriver::server	DEBUG	<- 200 OK {"value":["9289d526-ac40-d645-9324-5e0bed11c766"]}
[task 2022-02-24T06:42:02.570Z] 06:42:02     INFO - 
[task 2022-02-24T06:42:02.570Z] 06:42:02     INFO - TEST-PASS | /webdriver/tests/get_window_rect/user_prompts.py | test_accept[capabilities0-prompt-] 
[task 2022-02-24T06:42:02.570Z] 06:42:02     INFO - TEST-PASS | /webdriver/tests/get_window_rect/user_prompts.py | test_accept_and_notify[capabilities0-alert-None] 
[task 2022-02-24T06:42:02.570Z] 06:42:02     INFO - TEST-PASS | /webdriver/tests/get_window_rect/user_prompts.py | test_accept_and_notify[capabilities0-confirm-True] 
[task 2022-02-24T06:42:02.570Z] 06:42:02     INFO - TEST-PASS | /webdriver/tests/get_window_rect/user_prompts.py | test_accept_and_notify[capabilities0-prompt-] 
[task 2022-02-24T06:42:02.570Z] 06:42:02     INFO - TEST-PASS | /webdriver/tests/get_window_rect/user_prompts.py | test_dismiss[capabilities0-alert-None] 
[task 2022-02-24T06:42:02.570Z] 06:42:02     INFO - TEST-PASS | /webdriver/tests/get_window_rect/user_prompts.py | test_dismiss[capabilities0-confirm-False] 
[task 2022-02-24T06:42:02.570Z] 06:42:02     INFO - TEST-PASS | /webdriver/tests/get_window_rect/user_prompts.py | test_dismiss[capabilities0-prompt-None] 
[task 2022-02-24T06:42:02.570Z] 06:42:02     INFO - TEST-PASS | /webdriver/tests/get_window_rect/user_prompts.py | test_dismiss_and_notify[capabilities0-alert-None] 
[task 2022-02-24T06:42:02.570Z] 06:42:02     INFO - TEST-PASS | /webdriver/tests/get_window_rect/user_prompts.py | test_dismiss_and_notify[capabilities0-confirm-False] 
[task 2022-02-24T06:42:02.570Z] 06:42:02     INFO - TEST-PASS | /webdriver/tests/get_window_rect/user_prompts.py | test_dismiss_and_notify[capabilities0-prompt-None] 
[task 2022-02-24T06:42:02.570Z] 06:42:02     INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/get_window_rect/user_prompts.py | test_ignore[capabilities0-alert] - webdriver.error.TimeoutException: timeout (500): Timed out after 15.1 seconds with message: No user prompt with text 'alert' detected
[task 2022-02-24T06:42:02.570Z] 06:42:02     INFO - check_user_prompt_not_closed_but_exception = <function check_user_prompt_not_closed_but_exception.<locals>.check_user_prompt_not_closed_but_exception at 0x7fcf2af7ec20>
[task 2022-02-24T06:42:02.570Z] 06:42:02     INFO - dialog_type = 'alert'
[task 2022-02-24T06:42:02.570Z] 06:42:02     INFO - 
[task 2022-02-24T06:42:02.570Z] 06:42:02     INFO -     @pytest.mark.capabilities({"unhandledPromptBehavior": "ignore"})
[task 2022-02-24T06:42:02.570Z] 06:42:02     INFO -     @pytest.mark.parametrize("dialog_type", ["alert", "confirm", "prompt"])
[task 2022-02-24T06:42:02.570Z] 06:42:02     INFO -     def test_ignore(check_user_prompt_not_closed_but_exception, dialog_type):
[task 2022-02-24T06:42:02.570Z] 06:42:02     INFO - >       check_user_prompt_not_closed_but_exception(dialog_type)
[task 2022-02-24T06:42:02.571Z] 06:42:02     INFO - 
[task 2022-02-24T06:42:02.571Z] 06:42:02     INFO - check_user_prompt_not_closed_but_exception = <function check_user_prompt_not_closed_but_exception.<locals>.check_user_prompt_not_closed_but_exception at 0x7fcf2af7ec20>
[task 2022-02-24T06:42:02.571Z] 06:42:02     INFO - dialog_type = 'alert'
[task 2022-02-24T06:42:02.571Z] 06:42:02     INFO - 
[task 2022-02-24T06:42:02.571Z] 06:42:02     INFO - tests/web-platform/tests/webdriver/tests/get_window_rect/user_prompts.py:104: 
[task 2022-02-24T06:42:02.571Z] 06:42:02     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2022-02-24T06:42:02.571Z] 06:42:02     INFO - tests/web-platform/tests/webdriver/tests/get_window_rect/user_prompts.py:50: in check_user_prompt_not_closed_but_exception
[task 2022-02-24T06:42:02.571Z] 06:42:02     INFO -     create_dialog(dialog_type, text=dialog_type)
[task 2022-02-24T06:42:02.571Z] 06:42:02     INFO -         create_dialog = <function create_dialog.<locals>.create_dialog at 0x7fcf2af7eb00>
[task 2022-02-24T06:42:02.571Z] 06:42:02     INFO -         dialog_type = 'alert'
[task 2022-02-24T06:42:02.571Z] 06:42:02     INFO -         session    = <Session 902baabe-8499-ef42-9e07-7c4db23a9276>
[task 2022-02-24T06:42:02.571Z] 06:42:02     INFO - tests/web-platform/tests/webdriver/tests/support/fixtures.py:274: in create_dialog
[task 2022-02-24T06:42:02.571Z] 06:42:02     INFO -     wait.until(lambda s: s.alert.text == text)
[task 2022-02-24T06:42:02.571Z] 06:42:02     INFO -         dialog_type = 'alert'
[task 2022-02-24T06:42:02.571Z] 06:42:02     INFO -         session    = <Session 902baabe-8499-ef42-9e07-7c4db23a9276>
[task 2022-02-24T06:42:02.571Z] 06:42:02     INFO -         text       = 'alert'
[task 2022-02-24T06:42:02.571Z] 06:42:02     INFO -         wait       = <tests.support.sync.Poll object at 0x7fcf2af76310>
[task 2022-02-24T06:42:02.571Z] 06:42:02     INFO - tests/web-platform/tests/webdriver/tests/support/sync.py:141: in until
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -     raise self.exc_cls(message=message).with_traceback(tb)
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -         _          = <NoSuchAlertException http_status=404>
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -         condition  = <function create_dialog.<locals>.create_dialog.<locals>.<lambda> at 0x7fcf2af7e680>
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -         elapsed    = 15.1
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -         end        = 1645684920.456598
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -         interval_new = 0.0980370044708252
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -         message    = ("Timed out after 15.1 seconds with message: No user prompt with text 'alert' "
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -  'detected')
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -         next       = 1645684920.51504
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -         rv         = None
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -         self       = <tests.support.sync.Poll object at 0x7fcf2af76310>
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -         start      = 1645684905.456598
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -         tb         = <traceback object at 0x7fcf2afd2c80>
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO - tests/web-platform/tests/webdriver/tests/support/sync.py:117: in until
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -     rv = condition(self.session)
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -         _          = <NoSuchAlertException http_status=404>
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -         condition  = <function create_dialog.<locals>.create_dialog.<locals>.<lambda> at 0x7fcf2af7e680>
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -         elapsed    = 15.1
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -         end        = 1645684920.456598
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -         interval_new = 0.0980370044708252
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -         message    = ("Timed out after 15.1 seconds with message: No user prompt with text 'alert' "
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -  'detected')
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -         next       = 1645684920.51504
[task 2022-02-24T06:42:02.572Z] 06:42:02     INFO -         rv         = None
[task 2022-02-24T06:42:02.573Z] 06:42:02     INFO -         self       = <tests.support.sync.Poll object at 0x7fcf2af76310>
[task 2022-02-24T06:42:02.573Z] 06:42:02     INFO -         start      = 1645684905.456598
[task 2022-02-24T06:42:02.573Z] 06:42:02     INFO -         tb         = <traceback object at 0x7fcf2afd2c80>
[task 2022-02-24T06:42:02.573Z] 06:42:02     INFO - tests/web-platform/tests/webdriver/tests/support/fixtures.py:274: in <lambda>
[task 2022-02-24T06:42:02.573Z] 06:42:02     INFO -     wait.until(lambda s: s.alert.text == text)
[task 2022-02-24T06:42:02.573Z] 06:42:02     INFO -         s          = <Session 902baabe-8499-ef42-9e07-7c4db23a9276>
[task 2022-02-24T06:42:02.573Z] 06:42:02     INFO -         text       = 'alert'
[task 2022-02-24T06:42:02.573Z] 06:42:02     INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:19: in inner
[task 2022-02-24T06:42:02.573Z] 06:42:02     INFO -     return func(self, *args, **kwargs)
[task 2022-02-24T06:42:02.573Z] 06:42:02     INFO -         args       = ()
[task 2022-02-24T06:42:02.573Z] 06:42:02     INFO -         func       = <function UserPrompt.text at 0x7fcf29ef1a70>
[task 2022-02-24T06:42:02.573Z] 06:42:02     INFO -         kwargs     = {}
[task 2022-02-24T06:42:02.573Z] 06:42:02     INFO -         self       = <webdriver.client.UserPrompt object at 0x7fcf2afb9650>
[task 2022-02-24T06:42:02.574Z] 06:42:02     INFO -         session    = <Session 902baabe-8499-ef42-9e07-7c4db23a9276>
[task 2022-02-24T06:42:02.574Z] 06:42:02     INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:463: in text
[task 2022-02-24T06:42:02.574Z] 06:42:02     INFO -     return self.session.send_session_command("GET", "alert/text")
[task 2022-02-24T06:42:02.574Z] 06:42:02     INFO -         self       = <webdriver.client.UserPrompt object at 0x7fcf2afb9650>
[task 2022-02-24T06:42:02.574Z] 06:42:02     INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:640: in send_session_command
[task 2022-02-24T06:42:02.574Z] 06:42:02     INFO -     return self.send_command(method, url, body, timeout)
[task 2022-02-24T06:42:02.574Z] 06:42:02     INFO -         body       = None
[task 2022-02-24T06:42:02.574Z] 06:42:02     INFO -         method     = 'GET'
[task 2022-02-24T06:42:02.574Z] 06:42:02     INFO -         self       = <Session 902baabe-8499-ef42-9e07-7c4db23a9276>
[task 2022-02-24T06:42:02.574Z] 06:42:02     INFO -         timeout    = None
[task 2022-02-24T06:42:02.574Z] 06:42:02     INFO -         uri        = 'alert/text'
[task 2022-02-24T06:42:02.575Z] 06:42:02     INFO -         url        = 'session/902baabe-8499-ef42-9e07-7c4db23a9276/alert/text'
[task 2022-02-24T06:42:02.575Z] 06:42:02     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2022-02-24T06:42:02.575Z] 06:42:02     INFO - 
[task 2022-02-24T06:42:02.575Z] 06:42:02     INFO - self = <Session 902baabe-8499-ef42-9e07-7c4db23a9276>, method = 'GET'
[task 2022-02-24T06:42:02.575Z] 06:42:02     INFO - url = 'session/902baabe-8499-ef42-9e07-7c4db23a9276/alert/text', body = None
[task 2022-02-24T06:42:02.575Z] 06:42:02     INFO - timeout = None
[task 2022-02-24T06:42:02.575Z] 06:42:02     INFO - 
[task 2022-02-24T06:42:02.575Z] 06:42:02     INFO -     def send_command(self, method, url, body=None, timeout=None):
[task 2022-02-24T06:42:02.575Z] 06:42:02     INFO -         """
[task 2022-02-24T06:42:02.575Z] 06:42:02     INFO -         Send a command to the remote end and validate its success.
[task 2022-02-24T06:42:02.575Z] 06:42:02     INFO -     
[task 2022-02-24T06:42:02.575Z] 06:42:02     INFO -         :param method: HTTP method to use in request.
[task 2022-02-24T06:42:02.576Z] 06:42:02     INFO -         :param uri: "Command part" of the HTTP request URL,
[task 2022-02-24T06:42:02.576Z] 06:42:02     INFO -             e.g. `window/rect`.
[task 2022-02-24T06:42:02.576Z] 06:42:02     INFO -         :param body: Optional body of the HTTP request.
[task 2022-02-24T06:42:02.576Z] 06:42:02     INFO -     
[task 2022-02-24T06:42:02.576Z] 06:42:02     INFO -         :return: `None` if the HTTP response body was empty, otherwise
[task 2022-02-24T06:42:02.576Z] 06:42:02     INFO -             the `value` field returned after parsing the response
[task 2022-02-24T06:42:02.576Z] 06:42:02     INFO -             body as JSON.
[task 2022-02-24T06:42:02.576Z] 06:42:02     INFO -     
[task 2022-02-24T06:42:02.576Z] 06:42:02     INFO -         :raises error.WebDriverException: If the remote end returns
[task 2022-02-24T06:42:02.576Z] 06:42:02     INFO -             an error.
[task 2022-02-24T06:42:02.576Z] 06:42:02     INFO -         :raises ValueError: If the response body does not contain a
[task 2022-02-24T06:42:02.576Z] 06:42:02     INFO -             `value` key.
[task 2022-02-24T06:42:02.576Z] 06:42:02     INFO -         """
[task 2022-02-24T06:42:02.576Z] 06:42:02     INFO -     
[task 2022-02-24T06:42:02.576Z] 06:42:02     INFO -         response = self.transport.send(
[task 2022-02-24T06:42:02.576Z] 06:42:02     INFO -             method, url, body,
[task 2022-02-24T06:42:02.577Z] 06:42:02     INFO -             encoder=protocol.Encoder, decoder=protocol.Decoder,
[task 2022-02-24T06:42:02.577Z] 06:42:02     INFO -             session=self, timeout=timeout)
[task 2022-02-24T06:42:02.577Z] 06:42:02     INFO -     
[task 2022-02-24T06:42:02.577Z] 06:42:02     INFO -         if response.status != 200:
[task 2022-02-24T06:42:02.577Z] 06:42:02     INFO -             err = error.from_response(response)
[task 2022-02-24T06:42:02.577Z] 06:42:02     INFO -     
[task 2022-02-24T06:42:02.577Z] 06:42:02     INFO -             if isinstance(err, error.InvalidSessionIdException):
[task 2022-02-24T06:42:02.577Z] 06:42:02     INFO -                 # The driver could have already been deleted the session.
[task 2022-02-24T06:42:02.577Z] 06:42:02     INFO -                 self.session_id = None
[task 2022-02-24T06:42:02.577Z] 06:42:02     INFO -     
[task 2022-02-24T06:42:02.577Z] 06:42:02     INFO - >           raise err
[task 2022-02-24T06:42:02.578Z] 06:42:02     INFO - E           webdriver.error.TimeoutException: timeout (500): Timed out after 15.1 seconds with message: No user prompt with text 'alert' detected
[task 2022-02-24T06:42:02.578Z] 06:42:02     INFO - 
[task 2022-02-24T06:42:02.578Z] 06:42:02     INFO - body       = None
[task 2022-02-24T06:42:02.578Z] 06:42:02     INFO - err        = <NoSuchAlertException http_status=404>
[task 2022-02-24T06:42:02.578Z] 06:42:02     INFO - method     = 'GET'
[task 2022-02-24T06:42:02.578Z] 06:42:02     INFO - response   = <Response status=404 error=<NoSuchAlertException http_status=404>>
[task 2022-02-24T06:42:02.578Z] 06:42:02     INFO - self       = <Session 902baabe-8499-ef42-9e07-7c4db23a9276>
[task 2022-02-24T06:42:02.578Z] 06:42:02     INFO - timeout    = None
[task 2022-02-24T06:42:02.578Z] 06:42:02     INFO - url        = 'session/902baabe-8499-ef42-9e07-7c4db23a9276/alert/text'
[task 2022-02-24T06:42:02.578Z] 06:42:02     INFO - 
[task 2022-02-24T06:42:02.578Z] 06:42:02     INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:604: TimeoutException
[task 2022-02-24T06:42:02.581Z] 06:42:02     INFO - .....
[task 2022-02-24T06:42:02.581Z] 06:42:02     INFO - TEST-OK | /webdriver/tests/get_window_rect/user_prompts.py | took 28598ms

Henrik Skupin [:whimboo][⌚️UTC+2]

Updated

•

3 years ago

status-firefox97: --- → unaffected

status-firefox98: --- → unaffected

status-firefox99: --- → unaffected

status-firefox-esr91: --- → affected

Comment 2

•

3 years ago

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Status: NEW → RESOLVED

Closed: 3 years ago

Resolution: --- → INCOMPLETE

Ryan VanderMeulen [:RyanVM]

Updated

•

3 years ago

status-firefox-esr91: affected → wontfix

BMO Automation

Comment 3

•

2 years 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.

Bugzilla

Intermittent /webdriver/tests/get_window_rect/user_prompts.py | test_ignore[capabilities0-alert] - webdriver.error.TimeoutException: timeout (500): Timed out after 15.1 seconds with message: No user prompt with text 'alert' detected

Categories

(Remote Protocol :: Marionette, defect, P5)

Tracking

(firefox-esr91 wontfix, firefox97 unaffected, firefox98 unaffected, firefox99 unaffected)

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Crash Data

Security

(public)

User Story

Description

Updated

Comment 1

Comment 2

Updated

Comment 3