Closed Bug 1812418 Opened 2 years ago Closed 2 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/element_click/interactability.py | expected OK

Categories

(Remote Protocol :: Marionette, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED DUPLICATE of bug 1760291

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2023-01-25T19:05:26.574Z] 19:05:26     INFO - TEST-START | /webdriver/tests/element_click/interactability.py
[task 2023-01-25T19:05:26.639Z] 19:05:26     INFO - STDOUT: ============================= test session starts =============================
[task 2023-01-25T19:05:26.645Z] 19:05:26     INFO - STDOUT: platform win32 -- Python 3.9.5, pytest-7.0.1, pluggy-unknown -- Z:\task_167467113380889\build\venv\Scripts\python.exe
[task 2023-01-25T19:05:26.646Z] 19:05:26     INFO - STDOUT: rootdir: Z:\task_167467113380889\build
[task 2023-01-25T19:05:26.646Z] 19:05:26     INFO - STDOUT: plugins: asyncio-0.14.0
[task 2023-01-25T19:05:26.647Z] 19:05:26     INFO - STDOUT: collecting ... 
[task 2023-01-25T19:05:26.658Z] 19:05:26     INFO - STDOUT: collected 12 items
[task 2023-01-25T19:05:26.667Z] 19:05:26     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/element_click/interactability.py::test_display_none 
[task 2023-01-25T19:05:26.668Z] 19:05:26     INFO - PID 1156 | 1674673526667	webdriver::server	DEBUG	-> POST /session {"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"args": ["--profile", "C:\\Users\\task_167467113380 ... ata\\Local\\Temp\\tmpin3i2s1q.mozrunner"], "binary": "Z:\\task_167467113380889\\build\\application\\firefox\\firefox.exe"}}}}
[task 2023-01-25T19:05:26.669Z] 19:05:26     INFO - PID 1156 | 1674673526667	geckodriver::capabilities	DEBUG	Trying to read firefox version from ini files
[task 2023-01-25T19:05:26.670Z] 19:05:26     INFO - PID 1156 | 1674673526668	geckodriver::capabilities	DEBUG	Found version 111.0a1
[task 2023-01-25T19:05:26.670Z] 19:05:26     INFO - PID 1156 | 1674673526668	geckodriver::browser	DEBUG	Backing up prefs to "C:\\Users\\task_167467113380889\\AppData\\Local\\Temp\\tmpin3i2s1q.mozrunner\\user.geckodriver_backup"
[task 2023-01-25T19:05:26.682Z] 19:05:26     INFO - PID 1156 | 1674673526682	mozrunner::runner	INFO	Running command: "Z:\\task_167467113380889\\build\\application\\firefox\\firefox.exe" "--marionette" "--profile" "C:\\Users\\task_167467113380889\\AppData\\Local\\Temp\\tmpin3i2s1q.mozrunner" "-no-remote"
[task 2023-01-25T19:05:26.684Z] 19:05:26     INFO - PID 1156 | 1674673526684	geckodriver::marionette	DEBUG	Waiting 60s to connect to browser on 127.0.0.1
[task 2023-01-25T19:05:26.685Z] 19:05:26     INFO - PID 1156 | 1674673526684	geckodriver::browser	TRACE	Failed to open C:\Users\task_167467113380889\AppData\Local\Temp\tmpin3i2s1q.mozrunner\MarionetteActivePort
[task 2023-01-25T19:05:26.686Z] 19:05:26     INFO - PID 1156 | 1674673526684	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-01-25T19:05:26.744Z] 19:05:26     INFO - PID 1156 | *** You are running in headless mode.
[task 2023-01-25T19:05:26.793Z] 19:05:26     INFO - PID 1156 | 1674673526793	geckodriver::browser	TRACE	Failed to open C:\Users\task_167467113380889\AppData\Local\Temp\tmpin3i2s1q.mozrunner\MarionetteActivePort
[task 2023-01-25T19:05:26.795Z] 19:05:26     INFO - PID 1156 | 1674673526793	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-01-25T19:05:26.885Z] 19:05:26     INFO - PID 1156 | 1674673526889	Marionette	INFO	Marionette enabled
[task 2023-01-25T19:05:26.891Z] 19:05:26     INFO - PID 1156 | 1674673526891	Marionette	TRACE	Received observer notification final-ui-startup
[task 2023-01-25T19:05:26.892Z] 19:05:26     INFO - PID 1156 | 1674673526891	Marionette	INFO	Listening on port 52214
[task 2023-01-25T19:05:26.893Z] 19:05:26     INFO - PID 1156 | 1674673526892	Marionette	DEBUG	Marionette is listening
[task 2023-01-25T19:05:26.901Z] 19:05:26     INFO - PID 1156 | Read port: 52214
[task 2023-01-25T19:05:26.912Z] 19:05:26     INFO - PID 1156 | 1674673526920	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:52215
[task 2023-01-25T19:05:26.924Z] 19:05:26     INFO - PID 1156 | 1674673526922	geckodriver::marionette	DEBUG	Connection to Marionette established on 127.0.0.1:52214.
[task 2023-01-25T19:05:26.926Z] 19:05:26     INFO - PID 1156 | 1674673526925	Marionette	DEBUG	0 -> [0,1,"WebDriver:NewSession",{}]
[task 2023-01-25T19:05:26.927Z] 19:05:26     INFO - PID 1156 | 1674673526927	Marionette	DEBUG	Waiting for initial application window
[task 2023-01-25T19:05:27.001Z] 19:05:27     INFO - PID 1156 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-01-25T19:05:27.177Z] 19:05:27     INFO - PID 1156 | [GFX1-]: RenderCompositorSWGL failed mapping default framebuffer, no dt
[task 2023-01-25T19:05:30.171Z] 19:05:30     INFO - PID 1156 | 1674673530169	Marionette	DEBUG	0 -> [0,81,"WebDriver:FindElement",{"using":"css selector","value":"input"}]
[task 2023-01-25T19:05:30.171Z] 19:05:30     INFO - PID 1156 | 1674673530170	Marionette	TRACE	[14] MarionetteCommands actor created for window id 8589934597
[task 2023-01-25T19:05:30.172Z] 19:05:30     INFO - PID 1156 | 1674673530173	Marionette	DEBUG	0 <- [1,81,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"43eeb1f7-c99e-4f09-b305-1af6210b3cce"}}]
[task 2023-01-25T19:05:30.175Z] 19:05:30     INFO - PID 1156 | 1674673530173	webdriver::server	DEBUG	<- 200 OK {"value":{"element-6066-11e4-a52e-4f735466cecf":"43eeb1f7-c99e-4f09-b305-1af6210b3cce"}}
[task 2023-01-25T19:05:30.176Z] 19:05:30     INFO - PID 1156 | 1674673530175	webdriver::server	DEBUG	-> POST /session/1acfa6bd-5c8d-460b-aa9e-d1ee0216947b/element/43eeb1f7-c99e-4f09-b305-1af6210b3cce/click {}
[task 2023-01-25T19:05:30.176Z] 19:05:30     INFO - PID 1156 | 1674673530175	Marionette	DEBUG	0 -> [0,82,"WebDriver:ElementClick",{"id":"43eeb1f7-c99e-4f09-b305-1af6210b3cce"}]
[task 2023-01-25T19:05:30.181Z] 19:05:30     INFO - PID 1156 | 1674673530179	Marionette	DEBUG	0 <- [1,82,{"error":"element not interactable","message":"Element <input type=\"button\"> could not be scrolled into view","stacktrace":"RemoteError@chrome://remote/content/shared/RemoteError.sys.mjs:8:8\nWebDriverError@chrome://remote/content/shared/webdriver/Er ... eCommandsChild.sys.mjs:208:29\nreceiveMessage@chrome://remote/content/marionette/actors/MarionetteCommandsChild.sys.mjs:97:31\n"},null]
[task 2023-01-25T19:05:30.182Z] 19:05:30     INFO - PID 1156 | 1674673530180	webdriver::server	DEBUG	<- 400 Bad Request {"value":{"error":"element not interactable","message":"Element <input type=\"button\"> could not be scrol ... andsChild.sys.mjs:208:29\nreceiveMessage@chrome://remote/content/marionette/actors/MarionetteCommandsChild.sys.mjs:97:31\n"}}
[task 2023-01-25T19:05:30.183Z] 19:05:30     INFO - STDOUT: PASSED
[task 2023-01-25T19:05:30.183Z] 19:05:30     INFO - PID 1156 | 1674673530182	webdriver::server	DEBUG	-> POST /session/1acfa6bd-5c8d-460b-aa9e-d1ee0216947b/timeouts {"implicit": 0}
[task 2023-01-25T19:05:30.184Z] 19:05:30     INFO - PID 1156 | 1674673530182	Marionette	DEBUG	0 -> [0,83,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2023-01-25T19:05:30.184Z] 19:05:30     INFO - PID 1156 | 1674673530182	Marionette	DEBUG	0 <- [1,83,null,{"value":null}]
[task 2023-01-25T19:05:30.185Z] 19:05:30     INFO - PID 1156 | 1674673530183	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-01-25T19:05:30.186Z] 19:05:30     INFO - PID 1156 | 1674673530184	webdriver::server	DEBUG	-> POST /session/1acfa6bd-5c8d-460b-aa9e-d1ee0216947b/timeouts {"pageLoad": 300000}
[task 2023-01-25T19:05:30.187Z] 19:05:30     INFO - PID 1156 | 1674673530184	Marionette	DEBUG	0 -> [0,84,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2023-01-25T19:05:30.187Z] 19:05:30     INFO - PID 1156 | 1674673530184	Marionette	DEBUG	0 <- [1,84,null,{"value":null}]
[task 2023-01-25T19:05:30.188Z] 19:05:30     INFO - PID 1156 | 1674673530184	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-01-25T19:05:30.189Z] 19:05:30     INFO - PID 1156 | 1674673530185	webdriver::server	DEBUG	-> POST /session/1acfa6bd-5c8d-460b-aa9e-d1ee0216947b/timeouts {"script": 30000}
[task 2023-01-25T19:05:30.189Z] 19:05:30     INFO - PID 1156 | 1674673530186	Marionette	DEBUG	0 -> [0,85,"WebDriver:SetTimeouts",{"script":30000}]
[task 2023-01-25T19:05:30.190Z] 19:05:30     INFO - PID 1156 | 1674673530186	Marionette	DEBUG	0 <- [1,85,null,{"value":null}]
[task 2023-01-25T19:05:30.191Z] 19:05:30     INFO - PID 1156 | 1674673530186	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-01-25T19:05:30.191Z] 19:05:30     INFO - PID 1156 | 1674673530187	webdriver::server	DEBUG	-> GET /session/1acfa6bd-5c8d-460b-aa9e-d1ee0216947b/window
[task 2023-01-25T19:05:30.192Z] 19:05:30     INFO - PID 1156 | 1674673530187	Marionette	DEBUG	0 -> [0,86,"WebDriver:GetWindowHandle",{}]
[task 2023-01-25T19:05:30.193Z] 19:05:30     INFO - PID 1156 | 1674673530187	Marionette	DEBUG	0 <- [1,86,null,{"value":"32158efc-e242-407b-bb03-850ca78d161f"}]
[task 2023-01-25T19:05:30.194Z] 19:05:30     INFO - PID 1156 | 1674673530187	webdriver::server	DEBUG	<- 200 OK {"value":"32158efc-e242-407b-bb03-850ca78d161f"}
[task 2023-01-25T19:05:30.194Z] 19:05:30     INFO - PID 1156 | 1674673530188	webdriver::server	DEBUG	-> GET /session/1acfa6bd-5c8d-460b-aa9e-d1ee0216947b/window
[task 2023-01-25T19:05:30.195Z] 19:05:30     INFO - PID 1156 | 1674673530189	Marionette	DEBUG	0 -> [0,87,"WebDriver:GetWindowHandle",{}]
[task 2023-01-25T19:05:30.195Z] 19:05:30     INFO - PID 1156 | 1674673530189	Marionette	DEBUG	0 <- [1,87,null,{"value":"32158efc-e242-407b-bb03-850ca78d161f"}]
[task 2023-01-25T19:05:30.196Z] 19:05:30     INFO - PID 1156 | 1674673530189	webdriver::server	DEBUG	<- 200 OK {"value":"32158efc-e242-407b-bb03-850ca78d161f"}
[task 2023-01-25T19:05:30.197Z] 19:05:30     INFO - PID 1156 | 1674673530190	webdriver::server	DEBUG	-> GET /session/1acfa6bd-5c8d-460b-aa9e-d1ee0216947b/window/handles
[task 2023-01-25T19:05:30.198Z] 19:05:30     INFO - PID 1156 | 1674673530190	Marionette	DEBUG	0 -> [0,88,"WebDriver:GetWindowHandles",{}]
[task 2023-01-25T19:05:30.198Z] 19:05:30     INFO - PID 1156 | 1674673530190	Marionette	DEBUG	0 <- [1,88,null,["32158efc-e242-407b-bb03-850ca78d161f"]]
[task 2023-01-25T19:05:30.199Z] 19:05:30     INFO - PID 1156 | 1674673530191	webdriver::server	DEBUG	<- 200 OK {"value":["32158efc-e242-407b-bb03-850ca78d161f"]}
[task 2023-01-25T19:05:30.200Z] 19:05:30     INFO - PID 1156 | 1674673530191	webdriver::server	DEBUG	-> POST /session/1acfa6bd-5c8d-460b-aa9e-d1ee0216947b/window {"handle": "32158efc-e242-407b-bb03-850ca78d161f"}
[task 2023-01-25T19:05:30.201Z] 19:05:30     INFO - PID 1156 | 1674673530192	Marionette	DEBUG	0 -> [0,89,"WebDriver:SwitchToWindow",{"handle":"32158efc-e242-407b-bb03-850ca78d161f"}]
[task 2023-01-25T19:05:30.202Z] 19:05:30     INFO - PID 1156 | 1674673530192	Marionette	DEBUG	0 <- [1,89,null,{"value":null}]
[task 2023-01-25T19:05:30.202Z] 19:05:30     INFO - PID 1156 | 1674673530192	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-01-25T19:05:30.203Z] 19:05:30     INFO - PID 1156 | 1674673530193	webdriver::server	DEBUG	-> POST /session/1acfa6bd-5c8d-460b-aa9e-d1ee0216947b/alert/dismiss {}
[task 2023-01-25T19:05:30.204Z] 19:05:30     INFO - PID 1156 | 1674673530193	Marionette	DEBUG	0 -> [0,90,"WebDriver:DismissAlert",{}]
[task 2023-01-25T19:05:30.204Z] 19:05:30     INFO - PID 1156 | 1674673530194	Marionette	DEBUG	0 <- [1,90,{"error":"no such alert","message":"","stacktrace":"RemoteError@chrome://remote/content/shared/RemoteError.sys.mjs:8:8\nWebDriverError@chrome://remote/content/shared/webdriver/Er ... ote/content/marionette/server.sys.mjs:250:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.sys.mjs:496:20\n"},null]
[task 2023-01-25T19:05:30.206Z] 19:05:30     INFO - PID 1156 | 1674673530194	webdriver::server	DEBUG	<- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"RemoteError@chrome://remote/content/shared/Remo ... ontent/marionette/server.sys.mjs:250:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.sys.mjs:496:20\n"}}
[task 2023-01-25T19:05:30.206Z] 19:05:30     INFO - PID 1156 | 1674673530195	webdriver::server	DEBUG	-> POST /session/1acfa6bd-5c8d-460b-aa9e-d1ee0216947b/window {"handle": "32158efc-e242-407b-bb03-850ca78d161f"}
[task 2023-01-25T19:05:30.207Z] 19:05:30     INFO - PID 1156 | 1674673530195	Marionette	DEBUG	0 -> [0,91,"WebDriver:SwitchToWindow",{"handle":"32158efc-e242-407b-bb03-850ca78d161f"}]
[task 2023-01-25T19:05:30.208Z] 19:05:30     INFO - PID 1156 | 1674673530196	Marionette	DEBUG	0 <- [1,91,null,{"value":null}]
[task 2023-01-25T19:05:30.209Z] 19:05:30     INFO - PID 1156 | 1674673530196	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-01-25T19:05:30.209Z] 19:05:30     INFO - PID 1156 | 1674673530196	webdriver::server	DEBUG	-> GET /session/1acfa6bd-5c8d-460b-aa9e-d1ee0216947b/window
[task 2023-01-25T19:05:30.210Z] 19:05:30     INFO - PID 1156 | 1674673530197	Marionette	DEBUG	0 -> [0,92,"WebDriver:GetWindowHandle",{}]
[task 2023-01-25T19:05:30.211Z] 19:05:30     INFO - PID 1156 | 1674673530197	Marionette	DEBUG	0 <- [1,92,null,{"value":"32158efc-e242-407b-bb03-850ca78d161f"}]
[task 2023-01-25T19:05:30.212Z] 19:05:30     INFO - PID 1156 | 1674673530197	webdriver::server	DEBUG	<- 200 OK {"value":"32158efc-e242-407b-bb03-850ca78d161f"}
[task 2023-01-25T19:05:30.212Z] 19:05:30     INFO - PID 1156 | 1674673530198	webdriver::server	DEBUG	-> GET /session/1acfa6bd-5c8d-460b-aa9e-d1ee0216947b/window/handles
[task 2023-01-25T19:05:30.213Z] 19:05:30     INFO - PID 1156 | 1674673530198	Marionette	DEBUG	0 -> [0,93,"WebDriver:GetWindowHandles",{}]
[task 2023-01-25T19:05:30.214Z] 19:05:30     INFO - PID 1156 | 1674673530199	Marionette	DEBUG	0 <- [1,93,null,["32158efc-e242-407b-bb03-850ca78d161f"]]
[task 2023-01-25T19:05:30.215Z] 19:05:30     INFO - PID 1156 | 1674673530199	webdriver::server	DEBUG	<- 200 OK {"value":["32158efc-e242-407b-bb03-850ca78d161f"]}
[task 2023-01-25T19:05:30.215Z] 19:05:30     INFO - PID 1156 | 1674673530200	webdriver::server	DEBUG	-> POST /session/1acfa6bd-5c8d-460b-aa9e-d1ee0216947b/window {"handle": "32158efc-e242-407b-bb03-850ca78d161f"}
[task 2023-01-25T19:05:30.216Z] 19:05:30     INFO - PID 1156 | 1674673530200	Marionette	DEBUG	0 -> [0,94,"WebDriver:SwitchToWindow",{"handle":"32158efc-e242-407b-bb03-850ca78d161f"}]
[task 2023-01-25T19:05:30.217Z] 19:05:30     INFO - PID 1156 | 1674673530200	Marionette	DEBUG	0 <- [1,94,null,{"value":null}]
[task 2023-01-25T19:05:30.217Z] 19:05:30     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/element_click/interactability.py::test_element_not_interactable_css_transform[rotate(50deg)] 
[task 2023-01-25T19:05:30.218Z] 19:05:30     INFO - PID 1156 | 1674673530201	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-01-25T19:05:30.218Z] 19:05:30     INFO - PID 1156 | 1674673530202	webdriver::server	DEBUG	-> POST /session/1acfa6bd-5c8d-460b-aa9e-d1ee0216947b/window/rect {"width": 800, "height": 600}
[task 2023-01-25T19:05:30.219Z] 19:05:30     INFO - PID 1156 | 1674673530202	Marionette	DEBUG	0 -> [0,95,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2023-01-25T19:05:30.219Z] 19:05:30     INFO - PID 1156 | 1674673530202	Marionette	TRACE	Requested window geometry matches
[task 2023-01-25T19:05:30.220Z] 19:05:30     INFO - PID 1156 | 1674673530202	Marionette	DEBUG	0 <- [1,95,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2023-01-25T19:05:30.221Z] 19:05:30     INFO - PID 1156 | 1674673530202	webdriver::server	DEBUG	<- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2023-01-25T19:05:30.221Z] 19:05:30     INFO - PID 1156 | 1674673530203	webdriver::server	DEBUG	-> POST /session/1acfa6bd-5c8d-460b-aa9e-d1ee0216947b/frame {"id": null}
[task 2023-01-25T19:05:30.221Z] 19:05:30     INFO - PID 1156 | 1674673530204	Marionette	DEBUG	0 -> [0,96,"WebDriver:SwitchToFrame",{"id":null}]
[task 2023-01-25T19:05:30.221Z] 19:05:30     INFO - PID 1156 | 1674673530204	Marionette	DEBUG	0 <- [1,96,null,{"value":null}]
[task 2023-01-25T19:05:30.223Z] 19:05:30     INFO - PID 1156 | 1674673530205	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-01-25T19:05:30.223Z] 19:05:30     INFO - PID 1156 | 1674673530207	webdriver::server	DEBUG	-> POST /session/1acfa6bd-5c8d-460b-aa9e-d1ee0216947b/window/rect {"width": 800, "height": 600}
[task 2023-01-25T19:05:30.224Z] 19:05:30     INFO - PID 1156 | 1674673530208	Marionette	DEBUG	0 -> [0,97,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2023-01-25T19:05:30.224Z] 19:05:30     INFO - PID 1156 | 1674673530208	Marionette	TRACE	Requested window geometry matches
[task 2023-01-25T19:05:30.225Z] 19:05:30     INFO - PID 1156 | 1674673530208	Marionette	DEBUG	0 <- [1,97,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2023-01-25T19:05:30.225Z] 19:05:30     INFO - PID 1156 | 1674673530208	webdriver::server	DEBUG	<- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2023-01-25T19:05:30.226Z] 19:05:30     INFO - PID 1156 | 1674673530209	webdriver::server	DEBUG	-> POST /session/1acfa6bd-5c8d-460b-aa9e-d1ee0216947b/window/rect {"x": 100, "y": 100}
[task 2023-01-25T19:05:30.226Z] 19:05:30     INFO - PID 1156 | 1674673530210	Marionette	DEBUG	0 -> [0,98,"WebDriver:SetWindowRect",{"x":100,"y":100}]
[task 2023-01-25T19:05:30.227Z] 19:05:30     INFO - PID 1156 | 1674673530210	Marionette	TRACE	Requested window geometry matches
[task 2023-01-25T19:05:30.228Z] 19:05:30     INFO - PID 1156 | 1674673530210	Marionette	DEBUG	0 <- [1,98,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2023-01-25T19:05:30.228Z] 19:05:30     INFO - PID 1156 | 1674673530210	webdriver::server	DEBUG	<- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2023-01-25T19:05:30.229Z] 19:05:30     INFO - PID 1156 | 1674673530211	webdriver::server	DEBUG	-> POST /session/1acfa6bd-5c8d-460b-aa9e-d1ee0216947b/url {"url": "http://web-platform.test:8000/webdriver/tests/support/inli ... 3A+rotate%2850deg%29%3B%22%3E%0A++++++++++++%3Cinput+type%3Dbutton%3E%0A++++++++%3C%2Fdiv%3E&mime=text%2Fhtml&charset=UTF-8"}
[task 2023-01-25T19:05:30.230Z] 19:05:30     INFO - PID 1156 | 1674673530212	Marionette	DEBUG	0 -> [0,99,"WebDriver:Navigate",{"url":"http://web-platform.test:8000/webdriver/tests/support/inline.py?doc=%3C%21doctype+html%3E%0A%3Cmeta+charset%3DUTF-8%3E%0A%0A+ ... m%3A+rotate%2850deg%29%3B%22%3E%0A++++++++++++%3Cinput+type%3Dbutton%3E%0A++++++++%3C%2Fdiv%3E&mime=text%2Fhtml&charset=UTF-8"}]
[task 2023-01-25T19:05:30.231Z] 19:05:30     INFO - PID 1156 | 1674673530214	Marionette	TRACE	[14] Received event beforeunload for http://web-platform.test:8000/webdriver/tests/support/inline.py?doc=%3C%21doctype+html%3E%0A%3Cmeta+charset%3DUTF-8%3E%0A%0A+ ... %28-100px%2C+-100px%29%3B%22%3E%0A++++++++++++%3Cinput+type%3Dbutton%3E%0A++++++++%3C%2Fdiv%3E&mime=text%2Fhtml&charset=UTF-8
[task 2023-01-25T19:05:56.581Z] 19:05:56     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/element_click/interactability.py | expected OK
[task 2023-01-25T19:05:56.581Z] 19:05:56     INFO - TEST-INFO took 30006ms
[task 2023-01-25T19:05:58.928Z] 19:05:58     INFO - Closing logging queue
[task 2023-01-25T19:05:58.936Z] 19:05:58     INFO - queue closed
[task 2023-01-25T19:05:59.448Z] 19:05:59     INFO - PID 8168 | 1674673558951	geckodriver	INFO	Listening on 127.0.0.1:49874
[task 2023-01-25T19:05:59.450Z] 19:05:59     INFO - Starting runner
[task 2023-01-25T19:05:59.992Z] 19:05:59     INFO - TEST-START | /webdriver/tests/element_click/navigate.py

The test was waiting for a navigation to finish. Beside the beforeunload event nothing else has been received. Lets dupe to bug 1760291.

Status: NEW → RESOLVED
Closed: 2 years ago
Duplicate of bug: 1760291
Resolution: --- → DUPLICATE
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.