Closed Bug 1734034 Opened 2 years ago Closed 1 year ago

Intermitent - High frequency Linux/Windows /webdriver/tests/element_click/center_point.py | test_entirely_in_view - assert (222, <random>) == (222, 222)

Categories

(Remote Protocol :: Marionette, defect, P5)

defect

Tracking

(firefox100 fixed)

RESOLVED FIXED
100 Branch
Tracking Status
firefox100 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: jdescottes)

References

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell unknown], [wptsync upstream][stockwell unknown])

Attachments

(2 files, 1 obsolete file)

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


[task 2021-10-04T20:32:11.682Z] 20:32:11     INFO - STDOUT: PASSED
[task 2021-10-04T20:32:11.682Z] 20:32:11     INFO - PID 1578 | 1633379531678	webdriver::server	DEBUG	<- 200 OK {"value":[[5,5]]}
[task 2021-10-04T20:32:11.683Z] 20:32:11     INFO - PID 1578 | 1633379531680	webdriver::server	DEBUG	-> POST /session/c2f47432-6ae1-426e-960a-7c787ffb5884/timeouts {"implicit": 0}
[task 2021-10-04T20:32:11.683Z] 20:32:11     INFO - PID 1578 | 1633379531680	Marionette	DEBUG	0 -> [0,287,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2021-10-04T20:32:11.683Z] 20:32:11     INFO - PID 1578 | 1633379531680	Marionette	DEBUG	0 <- [1,287,null,{"value":null}]
[task 2021-10-04T20:32:11.683Z] 20:32:11     INFO - PID 1578 | 1633379531680	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2021-10-04T20:32:11.684Z] 20:32:11     INFO - PID 1578 | 1633379531681	webdriver::server	DEBUG	-> POST /session/c2f47432-6ae1-426e-960a-7c787ffb5884/timeouts {"pageLoad": 300000}
[task 2021-10-04T20:32:11.684Z] 20:32:11     INFO - PID 1578 | 1633379531682	Marionette	DEBUG	0 -> [0,288,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2021-10-04T20:32:11.685Z] 20:32:11     INFO - PID 1578 | 1633379531682	Marionette	DEBUG	0 <- [1,288,null,{"value":null}]
[task 2021-10-04T20:32:11.686Z] 20:32:11     INFO - PID 1578 | 1633379531683	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2021-10-04T20:32:11.686Z] 20:32:11     INFO - PID 1578 | 1633379531685	webdriver::server	DEBUG	-> POST /session/c2f47432-6ae1-426e-960a-7c787ffb5884/timeouts {"script": 30000}
[task 2021-10-04T20:32:11.687Z] 20:32:11     INFO - PID 1578 | 1633379531686	Marionette	DEBUG	0 -> [0,289,"WebDriver:SetTimeouts",{"script":30000}]
[task 2021-10-04T20:32:11.687Z] 20:32:11     INFO - PID 1578 | 1633379531686	Marionette	DEBUG	0 <- [1,289,null,{"value":null}]
[task 2021-10-04T20:32:11.688Z] 20:32:11     INFO - PID 1578 | 1633379531687	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2021-10-04T20:32:11.689Z] 20:32:11     INFO - PID 1578 | 1633379531688	webdriver::server	DEBUG	-> GET /session/c2f47432-6ae1-426e-960a-7c787ffb5884/window
[task 2021-10-04T20:32:11.690Z] 20:32:11     INFO - PID 1578 | 1633379531688	Marionette	DEBUG	0 -> [0,290,"WebDriver:GetWindowHandle",{}]
[task 2021-10-04T20:32:11.690Z] 20:32:11     INFO - PID 1578 | 1633379531688	Marionette	DEBUG	0 <- [1,290,null,{"value":"aa70401f-1f91-4c4f-a00d-76f8350c636c"}]
[task 2021-10-04T20:32:11.691Z] 20:32:11     INFO - PID 1578 | 1633379531689	webdriver::server	DEBUG	<- 200 OK {"value":"aa70401f-1f91-4c4f-a00d-76f8350c636c"}
[task 2021-10-04T20:32:11.692Z] 20:32:11     INFO - PID 1578 | 1633379531690	webdriver::server	DEBUG	-> GET /session/c2f47432-6ae1-426e-960a-7c787ffb5884/window
[task 2021-10-04T20:32:11.693Z] 20:32:11     INFO - PID 1578 | 1633379531690	Marionette	DEBUG	0 -> [0,291,"WebDriver:GetWindowHandle",{}]
[task 2021-10-04T20:32:11.694Z] 20:32:11     INFO - PID 1578 | 1633379531691	Marionette	DEBUG	0 <- [1,291,null,{"value":"aa70401f-1f91-4c4f-a00d-76f8350c636c"}]
[task 2021-10-04T20:32:11.695Z] 20:32:11     INFO - PID 1578 | 1633379531691	webdriver::server	DEBUG	<- 200 OK {"value":"aa70401f-1f91-4c4f-a00d-76f8350c636c"}
[task 2021-10-04T20:32:11.695Z] 20:32:11     INFO - PID 1578 | 1633379531692	webdriver::server	DEBUG	-> GET /session/c2f47432-6ae1-426e-960a-7c787ffb5884/window/handles
[task 2021-10-04T20:32:11.696Z] 20:32:11     INFO - PID 1578 | 1633379531692	Marionette	DEBUG	0 -> [0,292,"WebDriver:GetWindowHandles",{}]
[task 2021-10-04T20:32:11.697Z] 20:32:11     INFO - PID 1578 | 1633379531693	Marionette	DEBUG	0 <- [1,292,null,["aa70401f-1f91-4c4f-a00d-76f8350c636c"]]
[task 2021-10-04T20:32:11.698Z] 20:32:11     INFO - PID 1578 | 1633379531693	webdriver::server	DEBUG	<- 200 OK {"value":["aa70401f-1f91-4c4f-a00d-76f8350c636c"]}
[task 2021-10-04T20:32:11.698Z] 20:32:11     INFO - PID 1578 | 1633379531694	webdriver::server	DEBUG	-> POST /session/c2f47432-6ae1-426e-960a-7c787ffb5884/window {"handle": "aa70401f-1f91-4c4f-a00d-76f8350c636c"}
[task 2021-10-04T20:32:11.699Z] 20:32:11     INFO - PID 1578 | 1633379531695	Marionette	DEBUG	0 -> [0,293,"WebDriver:SwitchToWindow",{"handle":"aa70401f-1f91-4c4f-a00d-76f8350c636c","name":"aa70401f-1f91-4c4f-a00d-76f8350c636c"}]
[task 2021-10-04T20:32:11.709Z] 20:32:11     INFO - PID 1578 | 1633379531695	Marionette	DEBUG	0 <- [1,293,null,{"value":null}]
[task 2021-10-04T20:32:11.709Z] 20:32:11     INFO - PID 1578 | 1633379531696	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2021-10-04T20:32:11.709Z] 20:32:11     INFO - PID 1578 | 1633379531697	webdriver::server	DEBUG	-> POST /session/c2f47432-6ae1-426e-960a-7c787ffb5884/alert/dismiss {}
[task 2021-10-04T20:32:11.709Z] 20:32:11     INFO - PID 1578 | 1633379531697	Marionette	DEBUG	0 -> [0,294,"WebDriver:DismissAlert",{}]
[task 2021-10-04T20:32:11.710Z] 20:32:11     INFO - PID 1578 | 1633379531698	Marionette	DEBUG	0 <- [1,294,{"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 2021-10-04T20:32:11.710Z] 20:32:11     INFO - PID 1578 | 1633379531698	webdriver::server	DEBUG	<- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://remote/content/shared/webdriver/Errors.jsm:181:5\nNoSuchAlertError@chrome://remote/content/shared/webdriver/Errors.jsm:382:5\nGeckoDriver.prototype._checkIfAlertIsPresent@chrome://remote/content/marionette/driver.js:2496:11\nGeckoDriver.prototype.dismissDialog@chrome://remote/content/marionette/driver.js:2397: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"}}
[task 2021-10-04T20:32:11.710Z] 20:32:11     INFO - PID 1578 | 1633379531700	webdriver::server	DEBUG	-> POST /session/c2f47432-6ae1-426e-960a-7c787ffb5884/window {"handle": "aa70401f-1f91-4c4f-a00d-76f8350c636c"}
[task 2021-10-04T20:32:11.710Z] 20:32:11     INFO - PID 1578 | 1633379531700	Marionette	DEBUG	0 -> [0,295,"WebDriver:SwitchToWindow",{"handle":"aa70401f-1f91-4c4f-a00d-76f8350c636c","name":"aa70401f-1f91-4c4f-a00d-76f8350c636c"}]
[task 2021-10-04T20:32:11.710Z] 20:32:11     INFO - PID 1578 | 1633379531700	Marionette	DEBUG	0 <- [1,295,null,{"value":null}]
[task 2021-10-04T20:32:11.710Z] 20:32:11     INFO - PID 1578 | 1633379531701	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2021-10-04T20:32:11.710Z] 20:32:11     INFO - PID 1578 | 1633379531701	webdriver::server	DEBUG	-> GET /session/c2f47432-6ae1-426e-960a-7c787ffb5884/window
[task 2021-10-04T20:32:11.710Z] 20:32:11     INFO - PID 1578 | 1633379531702	Marionette	DEBUG	0 -> [0,296,"WebDriver:GetWindowHandle",{}]
[task 2021-10-04T20:32:11.711Z] 20:32:11     INFO - PID 1578 | 1633379531702	Marionette	DEBUG	0 <- [1,296,null,{"value":"aa70401f-1f91-4c4f-a00d-76f8350c636c"}]
[task 2021-10-04T20:32:11.711Z] 20:32:11     INFO - PID 1578 | 1633379531702	webdriver::server	DEBUG	<- 200 OK {"value":"aa70401f-1f91-4c4f-a00d-76f8350c636c"}
[task 2021-10-04T20:32:11.711Z] 20:32:11     INFO - PID 1578 | 1633379531703	webdriver::server	DEBUG	-> GET /session/c2f47432-6ae1-426e-960a-7c787ffb5884/window/handles
[task 2021-10-04T20:32:11.711Z] 20:32:11     INFO - PID 1578 | 1633379531703	Marionette	DEBUG	0 -> [0,297,"WebDriver:GetWindowHandles",{}]
[task 2021-10-04T20:32:11.711Z] 20:32:11     INFO - PID 1578 | 1633379531703	Marionette	DEBUG	0 <- [1,297,null,["aa70401f-1f91-4c4f-a00d-76f8350c636c"]]
[task 2021-10-04T20:32:11.711Z] 20:32:11     INFO - PID 1578 | 1633379531703	webdriver::server	DEBUG	<- 200 OK {"value":["aa70401f-1f91-4c4f-a00d-76f8350c636c"]}
[task 2021-10-04T20:32:11.711Z] 20:32:11     INFO - PID 1578 | 1633379531704	webdriver::server	DEBUG	-> POST /session/c2f47432-6ae1-426e-960a-7c787ffb5884/window {"handle": "aa70401f-1f91-4c4f-a00d-76f8350c636c"}
[task 2021-10-04T20:32:11.713Z] 20:32:11     INFO - PID 1578 | 1633379531704	Marionette	DEBUG	0 -> [0,298,"WebDriver:SwitchToWindow",{"handle":"aa70401f-1f91-4c4f-a00d-76f8350c636c","name":"aa70401f-1f91-4c4f-a00d-76f8350c636c"}]
[task 2021-10-04T20:32:11.713Z] 20:32:11     INFO - PID 1578 | 1633379531705	Marionette	DEBUG	0 <- [1,298,null,{"value":null}]
[task 2021-10-04T20:32:11.716Z] 20:32:11     INFO - PID 1578 | 1633379531705	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2021-10-04T20:32:11.717Z] 20:32:11     INFO - PID 1578 | 1633379531706	webdriver::server	DEBUG	-> POST /session/c2f47432-6ae1-426e-960a-7c787ffb5884/window/rect {"width": 800, "height": 600}
[task 2021-10-04T20:32:11.717Z] 20:32:11     INFO - PID 1578 | 1633379531706	Marionette	DEBUG	0 -> [0,299,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2021-10-04T20:32:11.718Z] 20:32:11     INFO - PID 1578 | 1633379531707	Marionette	DEBUG	0 <- [1,299,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2021-10-04T20:32:11.718Z] 20:32:11     INFO - PID 1578 | 1633379531707	webdriver::server	DEBUG	<- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2021-10-04T20:32:11.718Z] 20:32:11     INFO - PID 1578 | 1633379531708	webdriver::server	DEBUG	-> POST /session/c2f47432-6ae1-426e-960a-7c787ffb5884/frame {"id": null}
[task 2021-10-04T20:32:11.719Z] 20:32:11     INFO - PID 1578 | 1633379531708	Marionette	DEBUG	0 -> [0,300,"WebDriver:SwitchToFrame",{"id":null}]
[task 2021-10-04T20:32:11.719Z] 20:32:11     INFO - PID 1578 | 1633379531709	Marionette	DEBUG	0 <- [1,300,null,{"value":null}]
[task 2021-10-04T20:32:11.720Z] 20:32:11     INFO - PID 1578 | 1633379531709	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2021-10-04T20:32:11.721Z] 20:32:11     INFO - STDOUT: =================================== FAILURES ===================================
[task 2021-10-04T20:32:11.721Z] 20:32:11     INFO - STDOUT: ____________________________ test_entirely_in_view _____________________________
[task 2021-10-04T20:32:11.721Z] 20:32:11     INFO - STDOUT: session = <Session c2f47432-6ae1-426e-960a-7c787ffb5884>
[task 2021-10-04T20:32:11.721Z] 20:32:11     INFO - STDOUT: inline = <function inline.<locals>.inline at 0x7f4cbc0339d8>
[task 2021-10-04T20:32:11.721Z] 20:32:11     INFO - STDOUT:     def test_entirely_in_view(session, inline):
[task 2021-10-04T20:32:11.721Z] 20:32:11     INFO - STDOUT:         session.url = square(inline, 444)
[task 2021-10-04T20:32:11.721Z] 20:32:11     INFO - STDOUT:         element = session.find.css("#target", all=False)
[task 2021-10-04T20:32:11.722Z] 20:32:11     INFO - STDOUT:     
[task 2021-10-04T20:32:11.723Z] 20:32:11     INFO - STDOUT:         response = element_click(session, element)
[task 2021-10-04T20:32:11.723Z] 20:32:11     INFO - STDOUT:         assert_success(response)
[task 2021-10-04T20:32:11.724Z] 20:32:11     INFO - STDOUT:     
[task 2021-10-04T20:32:11.725Z] 20:32:11     INFO - STDOUT:         click_point = assert_one_click(session)
[task 2021-10-04T20:32:11.725Z] 20:32:11     INFO - STDOUT: >       assert click_point == (222, 222)
[task 2021-10-04T20:32:11.726Z] 20:32:11     INFO - STDOUT: E       assert (222, 209) == (222, 222)
[task 2021-10-04T20:32:11.727Z] 20:32:11     INFO - STDOUT: E         At index 1 diff: 209 != 222
[task 2021-10-04T20:32:11.734Z] 20:32:11     INFO - STDOUT: E         Full diff:
[task 2021-10-04T20:32:11.734Z] 20:32:11     INFO - STDOUT: E         - (222, 222)
[task 2021-10-04T20:32:11.734Z] 20:32:11     INFO - STDOUT: E         ?        ^^
[task 2021-10-04T20:32:11.734Z] 20:32:11     INFO - STDOUT: E         + (222, 209)
[task 2021-10-04T20:32:11.734Z] 20:32:11     INFO - STDOUT: E         ?        ^^
[task 2021-10-04T20:32:11.734Z] 20:32:11     INFO - STDOUT: click_point = (222, 209)
[task 2021-10-04T20:32:11.734Z] 20:32:11     INFO - STDOUT: element    = <Element 1a82c927-de5a-4d71-a16c-ac369672830b>
[task 2021-10-04T20:32:11.734Z] 20:32:11     INFO - STDOUT: inline     = <function inline.<locals>.inline at 0x7f4cbc0339d8>
[task 2021-10-04T20:32:11.734Z] 20:32:11     INFO - STDOUT: response   = <Responsetatus=200 body={"value": null}>
[task 2021-10-04T20:32:11.734Z] 20:32:11     INFO - STDOUT: session    = <Session c2f47432-6ae1-426e-960a-7c787ffb5884>
[task 2021-10-04T20:32:11.734Z] 20:32:11     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/element_click/center_point.py
[task 2021-10-04T20:32:11.734Z] 20:32:11     INFO - STDOUT: :51: AssertionError
[task 2021-10-04T20:32:11.734Z] 20:32:11     INFO - STDOUT: =========================== short test summary info ============================
[task 2021-10-04T20:32:11.734Z] 20:32:11     INFO - STDOUT: FAILED tests/web-platform/tests/webdriver/tests/element_click/center_point.py::test_entirely_in_view
[task 2021-10-04T20:32:11.734Z] 20:32:11     INFO - STDOUT: ========================= 1 failed, 10 passed in 3.44s =========================
[task 2021-10-04T20:32:11.735Z] 20:32:11     INFO - 
[task 2021-10-04T20:32:11.735Z] 20:32:11     INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/element_click/center_point.py | test_entirely_in_view - assert (222, 209) == (222, 222)
[task 2021-10-04T20:32:11.735Z] 20:32:11     INFO - session = <Session c2f47432-6ae1-426e-960a-7c787ffb5884>
[task 2021-10-04T20:32:11.735Z] 20:32:11     INFO - inline = <function inline.<locals>.inline at 0x7f4cbc0339d8>
[task 2021-10-04T20:32:11.735Z] 20:32:11     INFO - 
[task 2021-10-04T20:32:11.735Z] 20:32:11     INFO -     def test_entirely_in_view(session, inline):
[task 2021-10-04T20:32:11.735Z] 20:32:11     INFO -         session.url = square(inline, 444)
[task 2021-10-04T20:32:11.735Z] 20:32:11     INFO -         element = session.find.css("#target", all=False)
[task 2021-10-04T20:32:11.736Z] 20:32:11     INFO -     
[task 2021-10-04T20:32:11.736Z] 20:32:11     INFO -         response = element_click(session, element)
[task 2021-10-04T20:32:11.736Z] 20:32:11     INFO -         assert_success(response)
[task 2021-10-04T20:32:11.736Z] 20:32:11     INFO -     
[task 2021-10-04T20:32:11.736Z] 20:32:11     INFO -         click_point = assert_one_click(session)
[task 2021-10-04T20:32:11.736Z] 20:32:11     INFO - >       assert click_point == (222, 222)
[task 2021-10-04T20:32:11.736Z] 20:32:11     INFO - E       assert (222, 209) == (222, 222)
[task 2021-10-04T20:32:11.736Z] 20:32:11     INFO - E         At index 1 diff: 209 != 222
[task 2021-10-04T20:32:11.736Z] 20:32:11     INFO - E         Full diff:
[task 2021-10-04T20:32:11.737Z] 20:32:11     INFO - E         - (222, 222)
[task 2021-10-04T20:32:11.737Z] 20:32:11     INFO - E         ?        ^^
[task 2021-10-04T20:32:11.737Z] 20:32:11     INFO - E         + (222, 209)
[task 2021-10-04T20:32:11.737Z] 20:32:11     INFO - E         ?        ^^
[task 2021-10-04T20:32:11.737Z] 20:32:11     INFO - 
[task 2021-10-04T20:32:11.737Z] 20:32:11     INFO - click_point = (222, 209)
[task 2021-10-04T20:32:11.737Z] 20:32:11     INFO - element    = <Element 1a82c927-de5a-4d71-a16c-ac369672830b>
[task 2021-10-04T20:32:11.737Z] 20:32:11     INFO - inline     = <function inline.<locals>.inline at 0x7f4cbc0339d8>
[task 2021-10-04T20:32:11.737Z] 20:32:11     INFO - response   = <Responsetatus=200 body={"value": null}>
[task 2021-10-04T20:32:11.737Z] 20:32:11     INFO - session    = <Session c2f47432-6ae1-426e-960a-7c787ffb5884>
[task 2021-10-04T20:32:11.737Z] 20:32:11     INFO - 
[task 2021-10-04T20:32:11.737Z] 20:32:11     INFO - tests/web-platform/tests/webdriver/tests/element_click/center_point.py:51: AssertionError
Flags: needinfo?(hskupin)

Given that this is a perma failure has there been done a backfill yet? It should be clear that some landing regressed this test. Btw. there were no code changes in Marionette or geckodriver, which could have affected this test.

Note that this failure only occurs on Linux, so I would check for window manager like landings.

Flags: needinfo?(hskupin) → needinfo?(abutkovits)
OS: Unspecified → Linux
Hardware: Unspecified → x86_64
Summary: Perma /webdriver/tests/element_click/center_point.py | test_entirely_in_view - assert (222, 209) == (222, 222) → Perma Linux /webdriver/tests/element_click/center_point.py | test_entirely_in_view - assert (222, 209) == (222, 222)

The test had been backfilled but only the backfills are affected. Might it depend on a test which gets executed before?

Flags: needinfo?(abutkovits)

As it looks like the tests as run per chunk differs for backfill jobs. Take this as example:

https://treeherder.mozilla.org/jobs?repo=autoland&group_state=expanded&searchStr=linux%2Copt%2Cwdspec&revision=93c734e36aac9ad7db8f7d779683054f2203a431

The failing backfill job runs the tests in center_point.py, but the associated Wd3 job does not. Instead it's part of the Wd2 job.

Also the order of the tests is different. In both cases bubbling.py is run before, but then it differs. So most likely that the order of tests matter here.

Aryx, is there a specific chunking requested by backfill jobs? Where are these defined?

Flags: needinfo?(aryx.bugmail)

It's the same set of the tests like the ones in the task which gets backfilled.

Flags: needinfo?(aryx.bugmail)

(In reply to Sebastian Hengst [:aryx] (needinfo on intermittent or backout) from comment #6)

It's the same set of the tests like the ones in the task which gets backfilled.

Where are those defined in Taskcluster, and is it possible to trigger them on try?

Flags: needinfo?(aryx.bugmail)

./mach try fuzzy and passing the test folders as additional arguments should get you the same. Andrew, is that the recommended workflow to get a Try task with the same tests scheduled like an optimized task from autoland?

Flags: needinfo?(aryx.bugmail) → needinfo?(ahal)

Yep!

Flags: needinfo?(ahal)
Summary: Perma Linux /webdriver/tests/element_click/center_point.py | test_entirely_in_view - assert (222, 209) == (222, 222) → Perma Linux/Windows /webdriver/tests/element_click/center_point.py | test_entirely_in_view - assert (222, 209) == (222, 222)
OS: Linux → All
Hardware: x86_64 → All
Summary: Perma Linux/Windows /webdriver/tests/element_click/center_point.py | test_entirely_in_view - assert (222, 209) == (222, 222) → Perma backfill Linux/Windows /webdriver/tests/element_click/center_point.py | test_entirely_in_view - assert (222, 209) == (222, 222)
Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE

This shouldn't be fixed. We just didn't get any failures because no backfill jobs were most likely run.

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Summary: Perma backfill Linux/Windows /webdriver/tests/element_click/center_point.py | test_entirely_in_view - assert (222, 209) == (222, 222) → Perma backfill Linux/Windows /webdriver/tests/element_click/center_point.py | test_entirely_in_view - assert (222, <random>) == (222, 222)

Update:

There have been 45 failures within the last 7 days:

  • 25 failures on Linux 18.04 x64 WebRender asan opt
  • 15 failures on Linux 18.04 x64 WebRender opt
  • 2 failures on Windows 10 x86 2004 WebRender opt/debug
  • 3 failures on Windows 10 x64 2004 WebRender opt/debug

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=368240850&repo=autoland&lineNumber=3147

[task 2022-02-17T15:19:16.895Z] 15:19:16     INFO - STDOUT: =========================== short test summary info ============================
[task 2022-02-17T15:19:16.895Z] 15:19:16     INFO - STDOUT: FAILED tests/web-platform/tests/webdriver/tests/element_click/center_point.py::test_entirely_in_view
[task 2022-02-17T15:19:16.896Z] 15:19:16     INFO - STDOUT: ========================= 1 failed, 10 passed in 6.97s =========================
[task 2022-02-17T15:19:16.898Z] 15:19:16     INFO - 
[task 2022-02-17T15:19:16.898Z] 15:19:16     INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/element_click/center_point.py | test_entirely_in_view - assert (222, 211) == (222, 222)
[task 2022-02-17T15:19:16.898Z] 15:19:16     INFO - session = <Session b5ed204f-2623-4fb6-9236-76d6b60bcd76>
[task 2022-02-17T15:19:16.898Z] 15:19:16     INFO - inline = <function inline.<locals>.inline at 0x7f78eac27b70>
[task 2022-02-17T15:19:16.898Z] 15:19:16     INFO - 
[task 2022-02-17T15:19:16.898Z] 15:19:16     INFO -     def test_entirely_in_view(session, inline):
[task 2022-02-17T15:19:16.898Z] 15:19:16     INFO -         session.url = square(inline, 444)
[task 2022-02-17T15:19:16.899Z] 15:19:16     INFO -         element = session.find.css("#target", all=False)
[task 2022-02-17T15:19:16.899Z] 15:19:16     INFO -     
[task 2022-02-17T15:19:16.899Z] 15:19:16     INFO -         response = element_click(session, element)
[task 2022-02-17T15:19:16.899Z] 15:19:16     INFO -         assert_success(response)
[task 2022-02-17T15:19:16.899Z] 15:19:16     INFO -     
[task 2022-02-17T15:19:16.899Z] 15:19:16     INFO -         click_point = assert_one_click(session)
[task 2022-02-17T15:19:16.899Z] 15:19:16     INFO - >       assert click_point == (222, 222)
[task 2022-02-17T15:19:16.899Z] 15:19:16     INFO - E       assert (222, 211) == (222, 222)
[task 2022-02-17T15:19:16.899Z] 15:19:16     INFO - E         At index 1 diff: 211 != 222
[task 2022-02-17T15:19:16.899Z] 15:19:16     INFO - E         Full diff:
[task 2022-02-17T15:19:16.899Z] 15:19:16     INFO - E         - (222, 222)
[task 2022-02-17T15:19:16.899Z] 15:19:16     INFO - E         ?        ^^
[task 2022-02-17T15:19:16.899Z] 15:19:16     INFO - E         + (222, 211)
[task 2022-02-17T15:19:16.899Z] 15:19:16     INFO - E         ?        ^^
[task 2022-02-17T15:19:16.899Z] 15:19:16     INFO - 
[task 2022-02-17T15:19:16.899Z] 15:19:16     INFO - click_point = (222, 211)
[task 2022-02-17T15:19:16.900Z] 15:19:16     INFO - element    = <Element 19de4dd8-4175-4ce4-8b42-9953d9284191>
[task 2022-02-17T15:19:16.900Z] 15:19:16     INFO - inline     = <function inline.<locals>.inline at 0x7f78eac27b70>
[task 2022-02-17T15:19:16.900Z] 15:19:16     INFO - response   = <Responsetatus=200 body={"value": null}>
[task 2022-02-17T15:19:16.900Z] 15:19:16     INFO - session    = <Session b5ed204f-2623-4fb6-9236-76d6b60bcd76>
[task 2022-02-17T15:19:16.900Z] 15:19:16     INFO - 
[task 2022-02-17T15:19:16.900Z] 15:19:16     INFO - tests/web-platform/tests/webdriver/tests/element_click/center_point.py:51: AssertionError
[task 2022-02-17T15:19:16.904Z] 15:19:16     INFO - ..........
[task 2022-02-17T15:19:16.904Z] 15:19:16     INFO - TEST-OK | /webdriver/tests/element_click/center_point.py | took 7027ms
Whiteboard: [stockwell needswork:owner]

There have been 35 total failures in the last 7 days, recent failure log.
Affected platforms are

  • linux1804-64-asan-qr
  • linux1804-64-qr
Whiteboard: [stockwell unknown] → [stockwell unknown][stockwell needswork:owner]

There have been 64 total failures in the last 7 days, recent failure log.
Affected platforms are:

  • linux1804-64-asan-qr
  • linux1804-64-qr

So the test fails for the very first test in tests/web-platform/tests/webdriver/tests/element_click/center_point.py. Before this file we only run the tests in tests/web-platform/tests/webdriver/tests/element_click/bubbling.py. For non backfill jobs other test files run as well before.

We may wanna push a try build with these two tests only and adding a screenshot command before the failing line. As such we could examine the result via the logs as uploaded and maybe see if the element is wrongly rendered or if it's us reporting the wrong height. Note that remote.log.truncate should be set to false to see the transferred image.

Julian would you have the bandwidth to investigate this?

Flags: needinfo?(jdescottes)

Marian-Vasile: Looking at recent failures tracked here, they seem to be regular jobs (ie not backfills), is it fine with you if we rename this Bug to remove "backfill perma". At the moment it seems to be a regular (although high frequency) intermittent.

For the record it's also linux-asan + linux-debug only.

Flags: needinfo?(mlaza)

Hi, I renamed it, thanks for the info.

Flags: needinfo?(mlaza)
Summary: Perma backfill Linux/Windows /webdriver/tests/element_click/center_point.py | test_entirely_in_view - assert (222, <random>) == (222, 222) → Intermitent - High frequency Linux/Windows /webdriver/tests/element_click/center_point.py | test_entirely_in_view - assert (222, <random>) == (222, 222)

We noticed a few things:

  1. no failure for 3 days, even though nothing really changed on our side.
  2. all failures seem to occur when the chunk contains only a few tests. For instance this job only ran /webdriver/tests/element_click.
  3. this test only failed on autoland, never on central, which always runs the full suite

This leads me to believe that this test is unstable when some other test has not been executed before it.
I will try to repro on try by running only /webdriver/tests/element_click

Edit: definitely some issue about running this test in isolation: https://treeherder.mozilla.org/jobs?repo=try&revision=61ee90b0994115dfe3001fa3ac31f87f9e93fed6

The test fails because the element is bigger than the available viewport. It seems that the viewport is even further reduced because of a header to restore tabs from the previous session.

One solution would be to reduce the size of the tested element, although it would be great to avoid that header altogether (and understand why it appears intermittently)

Flags: needinfo?(jdescottes)
Attached image image.png

On linux the session restore infobar makes the viewport too small to display the target element entirely.
Reduce the size of the element to workaround this issue.

Assignee: nobody → jdescottes
See Also: → 1760259
Attachment #9268378 - Attachment description: Bug 1734034 - [remote] Disable session restore infobar for marionette tests, webplatform tests and geckodriver → Bug 1760259 - [remote] Disable session restore infobar for marionette tests, webplatform tests and geckodriver

Comment on attachment 9268378 [details]
Bug 1760259 - [remote] Disable session restore infobar for marionette tests, webplatform tests and geckodriver

Revision D141444 was moved to bug 1760259. Setting attachment 9268378 [details] to obsolete.

Attachment #9268378 - Attachment is obsolete: true
Pushed by jdescottes@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/14cc84d896e5
[wdspec] Reduce size of clicked element in test_entirely_in_view r=webdriver-reviewers,whimboo
Status: REOPENED → RESOLVED
Closed: 1 year ago1 year ago
Resolution: --- → FIXED
Target Milestone: --- → 100 Branch
Created web-platform-tests PR https://github.com/web-platform-tests/wpt/pull/33262 for changes under testing/web-platform/tests
Whiteboard: [stockwell unknown][stockwell needswork:owner] → [stockwell unknown][stockwell needswork:owner], [wptsync upstream]
Upstream PR merged by moz-wptsync-bot
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.