Open Bug 1945351 Opened 7 days ago Updated 6 days ago

Intermittent testing/firefox-ui/tests/functional/security/test_https_first.py TestHTTPSFirst.test_upgrade_with_schemeless_url | marionette_driver.errors.ScriptTimeoutException: Timed out after 30000 ms

Categories

(Testing :: Firefox UI Tests, defect, P5)

defect

Tracking

(Not tracked)

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2025-02-02T01:02:40.765Z] 01:02:40     INFO -  1738458160765	Marionette	DEBUG	8 -> [0,34,"WebDriver:GetPageSource",{}]
[task 2025-02-02T01:02:40.799Z] 01:02:40     INFO -  1738458160798	RemoteAgent	TRACE	WebDriverProcessData actor created for PID 1390
[task 2025-02-02T01:02:40.815Z] 01:02:40     INFO -  1738458160815	Marionette	TRACE	[25] MarionetteCommands actor created for window id 40802189313
[task 2025-02-02T01:02:40.833Z] 01:02:40     INFO -  1738458160833	Marionette	DEBUG	8 <- [1,34,null,{"value":"<html><head>\n    <title>Example Domain</title>\n\n    <meta charset=\"utf-8\">\n    <meta http-equiv=\"Content-type\" content=\"text/ ... for permission.</p>\n    <p><a href=\"https://www.iana.org/domains/example\">More information...</a></p>\n</div>\n\n\n</body></html>"}]
[task 2025-02-02T01:02:40.837Z] 01:02:40     INFO -  1738458160836	Marionette	DEBUG	8 -> [0,35,"Marionette:SetContext",{"value":"content"}]
[task 2025-02-02T01:02:40.838Z] 01:02:40     INFO -  1738458160838	Marionette	DEBUG	8 <- [1,35,null,{"value":null}]
[task 2025-02-02T01:02:40.874Z] 01:02:40     INFO - TEST-UNEXPECTED-ERROR | testing/firefox-ui/tests/functional/security/test_https_first.py TestHTTPSFirst.test_upgrade_with_schemeless_url | marionette_driver.errors.ScriptTimeoutException: Timed out after 30000 ms
[task 2025-02-02T01:02:40.874Z] 01:02:40     INFO - stacktrace:
[task 2025-02-02T01:02:40.874Z] 01:02:40     INFO - 	RemoteError@chrome://remote/content/shared/RemoteError.sys.mjs:8:8
[task 2025-02-02T01:02:40.874Z] 01:02:40     INFO - 	WebDriverError@chrome://remote/content/shared/webdriver/Errors.sys.mjs:197:5
[task 2025-02-02T01:02:40.875Z] 01:02:40     INFO - 	ScriptTimeoutError@chrome://remote/content/shared/webdriver/Errors.sys.mjs:722:5
[task 2025-02-02T01:02:40.875Z] 01:02:40     INFO - 	evaluate.sandbox/timeoutPromise</scriptTimeoutID<@chrome://remote/content/marionette/evaluate.sys.mjs:97:11
[task 2025-02-02T01:02:40.876Z] 01:02:40     INFO - 	notify@resource://gre/modules/Timer.sys.mjs:49:17
[task 2025-02-02T01:02:40.877Z] 01:02:40     INFO - Traceback (most recent call last):
[task 2025-02-02T01:02:40.877Z] 01:02:40     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_harness/marionette_test/testcases.py", line 198, in run
[task 2025-02-02T01:02:40.878Z] 01:02:40     INFO -     testMethod()
[task 2025-02-02T01:02:40.878Z] 01:02:40     INFO -   File "/builds/worker/workspace/build/tests/firefox-ui/tests/testing/firefox-ui/tests/functional/security/test_https_first.py", line 39, in test_upgrade_with_schemeless_url
[task 2025-02-02T01:02:40.879Z] 01:02:40     INFO -     self.wait_for_page_navigated(
[task 2025-02-02T01:02:40.879Z] 01:02:40     INFO -   File "/builds/worker/workspace/build/tests/firefox-ui/tests/testing/firefox-ui/tests/functional/security/test_https_first.py", line 81, in wait_for_page_navigated
[task 2025-02-02T01:02:40.880Z] 01:02:40     INFO -     Wait(self.marionette, ignored_exceptions=[JavascriptException]).until(
[task 2025-02-02T01:02:40.880Z] 01:02:40     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/wait.py", line 130, in until
[task 2025-02-02T01:02:40.880Z] 01:02:40     INFO -     rv = condition(self.marionette)
[task 2025-02-02T01:02:40.880Z] 01:02:40     INFO -   File "/builds/worker/workspace/build/tests/firefox-ui/tests/testing/firefox-ui/tests/functional/security/test_https_first.py", line 63, in navigated
[task 2025-02-02T01:02:40.881Z] 01:02:40     INFO -     return self.marionette.execute_async_script(
[task 2025-02-02T01:02:40.881Z] 01:02:40     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/marionette.py", line 1929, in execute_async_script
[task 2025-02-02T01:02:40.881Z] 01:02:40     INFO -     rv = self._send_message("WebDriver:ExecuteAsyncScript", body, key="value")
[task 2025-02-02T01:02:40.881Z] 01:02:40     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/decorators.py", line 24, in _
[task 2025-02-02T01:02:40.881Z] 01:02:40     INFO -     return func(*args, **kwargs)
[task 2025-02-02T01:02:40.881Z] 01:02:40     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/marionette.py", line 776, in _send_message
[task 2025-02-02T01:02:40.881Z] 01:02:40     INFO -     self._handle_error(err)
[task 2025-02-02T01:02:40.881Z] 01:02:40     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/marionette.py", line 788, in _handle_error
[task 2025-02-02T01:02:40.881Z] 01:02:40     INFO -     raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2025-02-02T01:02:40.881Z] 01:02:40     INFO - TEST-INFO took 30986ms
[task 2025-02-02T01:02:40.881Z] 01:02:40     INFO -  1738458160842	Marionette	DEBUG	8 -> [0,36,"Marionette:GetContext",{}]
[task 2025-02-02T01:02:40.882Z] 01:02:40     INFO -  1738458160843	Marionette	DEBUG	8 <- [1,36,null,{"value":"content"}]
[task 2025-02-02T01:02:40.882Z] 01:02:40     INFO -  1738458160846	Marionette	DEBUG	8 -> [0,37,"Marionette:SetContext",{"value":"chrome"}]
[task 2025-02-02T01:02:40.882Z] 01:02:40     INFO -  1738458160847	Marionette	DEBUG	8 <- [1,37,null,{"value":null}]
[task 2025-02-02T01:02:40.882Z] 01:02:40     INFO -  1738458160856	Marionette	DEBUG	8 -> [0,38,"WebDriver:ExecuteScript",{"script":"Services.perms.removeAll();","args":[],"newSandbox":true,"sandbox":"default","line":33,"filename":"tests/testing/firefox-ui/tests/functional/security/test_https_first.py"}]
[task 2025-02-02T01:02:40.888Z] 01:02:40     INFO -  1738458160887	Marionette	DEBUG	8 <- [1,38,null,{"value":null}]
[task 2025-02-02T01:02:40.891Z] 01:02:40     INFO -  1738458160890	Marionette	DEBUG	8 -> [0,39,"Marionette:SetContext",{"value":"content"}]
[task 2025-02-02T01:02:40.892Z] 01:02:40     INFO -  1738458160891	Marionette	DEBUG	8 <- [1,39,null,{"value":null}]
[task 2025-02-02T01:02:40.895Z] 01:02:40     INFO -  1738458160895	Marionette	DEBUG	8 -> [0,40,"Marionette:GetContext",{}]
[task 2025-02-02T01:02:40.896Z] 01:02:40     INFO -  1738458160896	Marionette	DEBUG	8 <- [1,40,null,{"value":"content"}]
[task 2025-02-02T01:02:40.899Z] 01:02:40     INFO -  1738458160899	Marionette	DEBUG	8 -> [0,41,"Marionette:SetContext",{"value":"chrome"}]
[task 2025-02-02T01:02:40.900Z] 01:02:40     INFO -  1738458160900	Marionette	DEBUG	8 <- [1,41,null,{"value":null}]
[task 2025-02-02T01:02:40.902Z] 01:02:40     INFO -  1738458160901	Marionette	DEBUG	8 -> [0,42,"WebDriver:GetWindowHandles",{}]
[task 2025-02-02T01:02:40.903Z] 01:02:40     INFO -  1738458160903	Marionette	DEBUG	8 <- [1,42,null,["0999a6bf-7957-4d3a-82cc-95fc7cf770c0"]]
[task 2025-02-02T01:02:40.905Z] 01:02:40     INFO -  1738458160905	Marionette	DEBUG	8 -> [0,43,"Marionette:SetContext",{"value":"content"}]
[task 2025-02-02T01:02:40.905Z] 01:02:40     INFO -  1738458160905	Marionette	DEBUG	8 <- [1,43,null,{"value":null}]
[task 2025-02-02T01:02:40.908Z] 01:02:40     INFO -  1738458160908	Marionette	DEBUG	8 -> [0,44,"Marionette:GetContext",{}]
[task 2025-02-02T01:02:40.909Z] 01:02:40     INFO -  1738458160908	Marionette	DEBUG	8 <- [1,44,null,{"value":"content"}]
[task 2025-02-02T01:02:40.911Z] 01:02:40     INFO -  1738458160911	Marionette	DEBUG	8 -> [0,45,"Marionette:SetContext",{"value":"content"}]
[task 2025-02-02T01:02:40.912Z] 01:02:40     INFO -  1738458160911	Marionette	DEBUG	8 <- [1,45,null,{"value":null}]
[task 2025-02-02T01:02:40.913Z] 01:02:40     INFO -  1738458160913	Marionette	DEBUG	8 -> [0,46,"WebDriver:GetWindowHandles",{}]
[task 2025-02-02T01:02:40.915Z] 01:02:40     INFO -  1738458160915	Marionette	DEBUG	8 <- [1,46,null,["2eac694d-c496-402a-99c4-2cbeae82adb9"]]
[task 2025-02-02T01:02:40.918Z] 01:02:40     INFO -  1738458160917	Marionette	DEBUG	8 -> [0,47,"Marionette:SetContext",{"value":"content"}]
[task 2025-02-02T01:02:40.918Z] 01:02:40     INFO -  1738458160918	Marionette	DEBUG	8 <- [1,47,null,{"value":null}]
[task 2025-02-02T01:02:40.920Z] 01:02:40     INFO -  1738458160920	Marionette	DEBUG	8 -> [0,48,"WebDriver:DeleteSession",{}]
[task 2025-02-02T01:02:40.923Z] 01:02:40     INFO -  1738458160922	Marionette	TRACE	[25] MarionetteCommands actor destroyed for window id 40802189313
[task 2025-02-02T01:02:40.924Z] 01:02:40     INFO -  1738458160923	Marionette	TRACE	[1] MarionetteCommands actor destroyed for window id 2
[task 2025-02-02T01:02:40.924Z] 01:02:40     INFO -  1738458160923	Marionette	TRACE	[9] MarionetteCommands actor destroyed for window id 38654705665
[task 2025-02-02T01:02:40.933Z] 01:02:40     INFO -  1738458160932	Marionette	DEBUG	8 <- [1,48,null,{"value":null}]
[task 2025-02-02T01:02:40.935Z] 01:02:40     INFO - TEST-START | testing/firefox-ui/tests/functional/security/test_ssl_status_after_restart.py TestSSLStatusAfterRestart.test_ssl_status_after_restart
You need to log in before you can comment on or make changes to this bug.