Closed
Bug 1925729
Opened 1 year ago
Closed 1 year ago
Intermittent /webdriver/tests/classic/execute_async_script/user_prompts.py | test_dismiss[capabilities0-beforeunload-None] - webdriver.error.TimeoutException: timeout (500): Timed out after 5.0 seconds with message: Target page did not load
Categories
(Remote Protocol :: Marionette, defect, P5)
Remote Protocol
Marionette
Tracking
(Not tracked)
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
References
(Depends on 1 open bug)
Details
(Keywords: intermittent-failure)
Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=478978828&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/A6OKV7jdRkqMmnZQkaXRgw/runs/0/artifacts/public/logs/live_backing.log
[task 2024-10-19T03:52:33.905Z] 03:52:33 INFO - TEST-PASS | /webdriver/tests/classic/execute_async_script/user_prompts.py | test_accept[capabilities0-confirm-True]
[task 2024-10-19T03:52:33.905Z] 03:52:33 INFO - TEST-PASS | /webdriver/tests/classic/execute_async_script/user_prompts.py | test_accept[capabilities0-prompt-]
[task 2024-10-19T03:52:33.905Z] 03:52:33 INFO - TEST-PASS | /webdriver/tests/classic/execute_async_script/user_prompts.py | test_accept_and_notify[capabilities0-alert-None]
[task 2024-10-19T03:52:33.905Z] 03:52:33 INFO - TEST-PASS | /webdriver/tests/classic/execute_async_script/user_prompts.py | test_accept_and_notify[capabilities0-beforeunload-None]
[task 2024-10-19T03:52:33.905Z] 03:52:33 INFO - TEST-PASS | /webdriver/tests/classic/execute_async_script/user_prompts.py | test_accept_and_notify[capabilities0-confirm-True]
[task 2024-10-19T03:52:33.905Z] 03:52:33 INFO - TEST-PASS | /webdriver/tests/classic/execute_async_script/user_prompts.py | test_accept_and_notify[capabilities0-prompt-]
[task 2024-10-19T03:52:33.905Z] 03:52:33 INFO - TEST-PASS | /webdriver/tests/classic/execute_async_script/user_prompts.py | test_dismiss[capabilities0-alert-None]
[task 2024-10-19T03:52:33.905Z] 03:52:33 INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/classic/execute_async_script/user_prompts.py | test_dismiss[capabilities0-beforeunload-None] - webdriver.error.TimeoutException: timeout (500): Timed out after 5.0 seconds with message: Target page did not load
[task 2024-10-19T03:52:33.905Z] 03:52:33 INFO - check_beforeunload_implicitly_accepted = <function check_beforeunload_implicitly_accepted.<locals>.check_beforeunload_implicitly_accepted at 0x0000029960B76DE0>
[task 2024-10-19T03:52:33.905Z] 03:52:33 INFO - check_user_prompt_closed_without_exception = <function check_user_prompt_closed_without_exception.<locals>.check_user_prompt_closed_without_exception at 0x0000029960B76AC0>
[task 2024-10-19T03:52:33.905Z] 03:52:33 INFO - dialog_type = 'beforeunload', retval = None
[task 2024-10-19T03:52:33.905Z] 03:52:33 INFO -
[task 2024-10-19T03:52:33.905Z] 03:52:33 INFO - @pytest.mark.capabilities({"unhandledPromptBehavior": "dismiss"})
[task 2024-10-19T03:52:33.905Z] 03:52:33 INFO - @pytest.mark.parametrize("dialog_type, retval", [
[task 2024-10-19T03:52:33.905Z] 03:52:33 INFO - ("alert", None),
[task 2024-10-19T03:52:33.905Z] 03:52:33 INFO - ("beforeunload", None),
[task 2024-10-19T03:52:33.905Z] 03:52:33 INFO - ("confirm", False),
[task 2024-10-19T03:52:33.906Z] 03:52:33 INFO - ("prompt", None),
[task 2024-10-19T03:52:33.906Z] 03:52:33 INFO - ])
[task 2024-10-19T03:52:33.906Z] 03:52:33 INFO - def test_dismiss(
[task 2024-10-19T03:52:33.906Z] 03:52:33 INFO - check_beforeunload_implicitly_accepted,
[task 2024-10-19T03:52:33.906Z] 03:52:33 INFO - check_user_prompt_closed_without_exception,
[task 2024-10-19T03:52:33.906Z] 03:52:33 INFO - dialog_type,
[task 2024-10-19T03:52:33.906Z] 03:52:33 INFO - retval
[task 2024-10-19T03:52:33.906Z] 03:52:33 INFO - ):
[task 2024-10-19T03:52:33.906Z] 03:52:33 INFO - if dialog_type == "beforeunload":
[task 2024-10-19T03:52:33.906Z] 03:52:33 INFO - > check_beforeunload_implicitly_accepted()
[task 2024-10-19T03:52:33.906Z] 03:52:33 INFO -
[task 2024-10-19T03:52:33.906Z] 03:52:33 INFO - check_beforeunload_implicitly_accepted = <function check_beforeunload_implicitly_accepted.<locals>.check_beforeunload_implicitly_accepted at 0x0000029960B76DE0>
[task 2024-10-19T03:52:33.906Z] 03:52:33 INFO - check_user_prompt_closed_without_exception = <function check_user_prompt_closed_without_exception.<locals>.check_user_prompt_closed_without_exception at 0x0000029960B76AC0>
[task 2024-10-19T03:52:33.906Z] 03:52:33 INFO - dialog_type = 'beforeunload'
[task 2024-10-19T03:52:33.906Z] 03:52:33 INFO - retval = None
[task 2024-10-19T03:52:33.906Z] 03:52:33 INFO -
[task 2024-10-19T03:52:33.906Z] 03:52:33 INFO - tests\web-platform\tests\webdriver\tests\classic\execute_async_script\user_prompts.py:144:
[task 2024-10-19T03:52:33.907Z] 03:52:33 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2024-10-19T03:52:33.907Z] 03:52:33 INFO - tests\web-platform\tests\webdriver\tests\classic\execute_async_script\user_prompts.py:35: in check_beforeunload_implicitly_accepted
[task 2024-10-19T03:52:33.907Z] 03:52:33 INFO - wait.until(lambda s: s.url == page_target)
[task 2024-10-19T03:52:33.907Z] 03:52:33 INFO - element = <WebElement 3ff3d281-8163-403f-bbd9-b50aa3f14420>
[task 2024-10-19T03:52:33.907Z] 03:52:33 INFO - page_beforeunload = 'https://web-platform.test:8443/webdriver/tests/support/html/beforeunload.html'
[task 2024-10-19T03:52:33.907Z] 03:52:33 INFO - page_target = 'https://web-platform.test:8443/webdriver/tests/support/html/default.html'
[task 2024-10-19T03:52:33.907Z] 03:52:33 INFO - response = <Response: status=200 body={"value": null}>
[task 2024-10-19T03:52:33.907Z] 03:52:33 INFO - session = <Session 31546c8c-dcb9-446b-bcf0-7798ca3ece58>
[task 2024-10-19T03:52:33.907Z] 03:52:33 INFO - url = <function url.<locals>.url at 0x0000029960B24360>
[task 2024-10-19T03:52:33.907Z] 03:52:33 INFO - wait = <tests.support.sync.Poll object at 0x0000029960C3F010>
[task 2024-10-19T03:52:33.907Z] 03:52:33 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2024-10-19T03:52:33.907Z] 03:52:33 INFO -
[task 2024-10-19T03:52:33.907Z] 03:52:33 INFO - self = <tests.support.sync.Poll object at 0x0000029960C3F010>
[task 2024-10-19T03:52:33.907Z] 03:52:33 INFO - condition = <function check_beforeunload_implicitly_accepted.<locals>.check_beforeunload_implicitly_accepted.<locals>.<lambda> at 0x0000029960B765C0>
[task 2024-10-19T03:52:33.907Z] 03:52:33 INFO -
[task 2024-10-19T03:52:33.907Z] 03:52:33 INFO - def until(self, condition):
[task 2024-10-19T03:52:33.907Z] 03:52:33 INFO - """
[task 2024-10-19T03:52:33.907Z] 03:52:33 INFO - This will repeatedly evaluate `condition` in anticipation
[task 2024-10-19T03:52:33.907Z] 03:52:33 INFO - for a truthy return value, or the timeout to expire.
[task 2024-10-19T03:52:33.907Z] 03:52:33 INFO -
[task 2024-10-19T03:52:33.907Z] 03:52:33 INFO - A condition that returns `None` or does not evaluate to
[task 2024-10-19T03:52:33.908Z] 03:52:33 INFO - true will fully elapse its timeout before raising, unless
[task 2024-10-19T03:52:33.908Z] 03:52:33 INFO - the `raises` keyword argument is `None`, in which case the
[task 2024-10-19T03:52:33.908Z] 03:52:33 INFO - condition's return value is propagated unconditionally.
[task 2024-10-19T03:52:33.908Z] 03:52:33 INFO -
[task 2024-10-19T03:52:33.908Z] 03:52:33 INFO - If an exception is raised in `condition` and it's not ignored,
[task 2024-10-19T03:52:33.908Z] 03:52:33 INFO - this function will raise immediately. If the exception is
[task 2024-10-19T03:52:33.908Z] 03:52:33 INFO - ignored it will be swallowed and polling will resume until
[task 2024-10-19T03:52:33.908Z] 03:52:33 INFO - either the condition meets the return requirements or the
[task 2024-10-19T03:52:33.908Z] 03:52:33 INFO - timeout duration is reached.
[task 2024-10-19T03:52:33.908Z] 03:52:33 INFO -
[task 2024-10-19T03:52:33.908Z] 03:52:33 INFO - :param condition: A callable function whose return value will
[task 2024-10-19T03:52:33.908Z] 03:52:33 INFO - be returned by this function.
[task 2024-10-19T03:52:33.908Z] 03:52:33 INFO - """
[task 2024-10-19T03:52:33.908Z] 03:52:33 INFO - rv = None
[task 2024-10-19T03:52:33.908Z] 03:52:33 INFO - tb = None
[task 2024-10-19T03:52:33.908Z] 03:52:33 INFO - start = self.clock.time()
[task 2024-10-19T03:52:33.908Z] 03:52:33 INFO - end = start + self.timeout
[task 2024-10-19T03:52:33.908Z] 03:52:33 INFO -
[task 2024-10-19T03:52:33.908Z] 03:52:33 INFO - while not self.clock.time() >= end:
[task 2024-10-19T03:52:33.908Z] 03:52:33 INFO - try:
[task 2024-10-19T03:52:33.908Z] 03:52:33 INFO - next = self.clock.time() + self.interval
[task 2024-10-19T03:52:33.909Z] 03:52:33 INFO - rv = condition(self.session)
[task 2024-10-19T03:52:33.909Z] 03:52:33 INFO - except (KeyboardInterrupt, SystemExit):
[task 2024-10-19T03:52:33.909Z] 03:52:33 INFO - raise
[task 2024-10-19T03:52:33.909Z] 03:52:33 INFO - except self.exceptions:
[task 2024-10-19T03:52:33.909Z] 03:52:33 INFO - _, _, tb = sys.exc_info()
[task 2024-10-19T03:52:33.909Z] 03:52:33 INFO -
[task 2024-10-19T03:52:33.909Z] 03:52:33 INFO - # re-adjust the interval depending on how long
[task 2024-10-19T03:52:33.909Z] 03:52:33 INFO - # the callback took to evaluate the condition
[task 2024-10-19T03:52:33.909Z] 03:52:33 INFO - interval_new = max(next - self.clock.time(), 0)
[task 2024-10-19T03:52:33.909Z] 03:52:33 INFO -
[task 2024-10-19T03:52:33.909Z] 03:52:33 INFO - if not rv:
[task 2024-10-19T03:52:33.909Z] 03:52:33 INFO - self.clock.sleep(interval_new)
[task 2024-10-19T03:52:33.909Z] 03:52:33 INFO - continue
[task 2024-10-19T03:52:33.909Z] 03:52:33 INFO -
[task 2024-10-19T03:52:33.909Z] 03:52:33 INFO - if rv is not None:
[task 2024-10-19T03:52:33.909Z] 03:52:33 INFO - return rv
[task 2024-10-19T03:52:33.909Z] 03:52:33 INFO -
[task 2024-10-19T03:52:33.909Z] 03:52:33 INFO - self.clock.sleep(interval_new)
[task 2024-10-19T03:52:33.910Z] 03:52:33 INFO -
[task 2024-10-19T03:52:33.910Z] 03:52:33 INFO - if self.exc_cls is not None:
[task 2024-10-19T03:52:33.910Z] 03:52:33 INFO - elapsed = round((self.clock.time() - start), 1)
[task 2024-10-19T03:52:33.910Z] 03:52:33 INFO - message = "Timed out after {} seconds".format(elapsed)
[task 2024-10-19T03:52:33.910Z] 03:52:33 INFO - if self.exc_msg is not None:
[task 2024-10-19T03:52:33.910Z] 03:52:33 INFO - message = "{} with message: {}".format(message, self.exc_msg)
[task 2024-10-19T03:52:33.910Z] 03:52:33 INFO - > raise self.exc_cls(message=message).with_traceback(tb)
[task 2024-10-19T03:52:33.910Z] 03:52:33 INFO - E webdriver.error.TimeoutException: timeout (500): Timed out after 5.0 seconds with message: Target page did not load
[task 2024-10-19T03:52:33.910Z] 03:52:33 INFO -
[task 2024-10-19T03:52:33.911Z] 03:52:33 INFO - condition = <function check_beforeunload_implicitly_accepted.<locals>.check_beforeunload_implicitly_accepted.<locals>.<lambda> at 0x0000029960B765C0>
[task 2024-10-19T03:52:33.911Z] 03:52:33 INFO - elapsed = 5.0
[task 2024-10-19T03:52:33.911Z] 03:52:33 INFO - end = 1729309940.4009802
[task 2024-10-19T03:52:33.911Z] 03:52:33 INFO - interval_new = 0.09799003601074219
[task 2024-10-19T03:52:33.911Z] 03:52:33 INFO - message = 'Timed out after 5.0 seconds with message: Target page did not load'
[task 2024-10-19T03:52:33.911Z] 03:52:33 INFO - next = 1729309940.4360337
[task 2024-10-19T03:52:33.911Z] 03:52:33 INFO - rv = False
[task 2024-10-19T03:52:33.911Z] 03:52:33 INFO - self = <tests.support.sync.Poll object at 0x0000029960C3F010>
[task 2024-10-19T03:52:33.911Z] 03:52:33 INFO - start = 1729309935.4009802
[task 2024-10-19T03:52:33.911Z] 03:52:33 INFO - tb = None
[task 2024-10-19T03:52:33.911Z] 03:52:33 INFO -
[task 2024-10-19T03:52:33.911Z] 03:52:33 INFO - tests\web-platform\tests\webdriver\tests\support\sync.py:142: TimeoutException
[task 2024-10-19T03:52:33.918Z] 03:52:33 INFO - ..............
[task 2024-10-19T03:52:33.918Z] 03:52:33 INFO - TEST-OK | /webdriver/tests/classic/execute_async_script/user_prompts.py | took 30765ms
[task 2024-10-19T03:52:43.918Z] 03:52:43 WARNING - Forcibly terminating runner process
[task 2024-10-19T03:52:43.927Z] 03:52:43 INFO - Starting WebDriver: D:\task_172930933647733\fetches\geckodriver.exe --host 127.0.0.1 --port 0 -vv --binary D:\task_172930933647733\build\application\firefox\firefox.exe
[task 2024-10-19T03:52:44.030Z] 03:52:44 INFO - PID 2940 | 1729309963943 geckodriver INFO Listening on 127.0.0.1:55546
[task 2024-10-19T03:52:44.033Z] 03:52:44 INFO - Webdriver started successfully.
[task 2024-10-19T03:52:44.033Z] 03:52:44 INFO - Starting runner
[task 2024-10-19T03:52:44.667Z] 03:52:44 INFO - TEST-START | /webdriver/tests/classic/execute_async_script/window.py
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 3•1 year ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•