Closed Bug 1853959 Opened 8 months ago Closed 8 months ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/input/perform_actions/invalid.py | expected OK

Categories

(Remote Protocol :: WebDriver BiDi, defect, P1)

defect
Points:
1

Tracking

(firefox-esr102 unaffected, firefox-esr115 unaffected, firefox117 unaffected, firefox118 unaffected, firefox119 fixed)

RESOLVED FIXED
119 Branch
Tracking Status
firefox-esr102 --- unaffected
firefox-esr115 --- unaffected
firefox117 --- unaffected
firefox118 --- unaffected
firefox119 --- fixed

People

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

References

(Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [webdriver:m8], [wptsync upstream])

Attachments

(1 file)

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


[task 2023-09-19T15:31:35.735Z] 15:31:35     INFO - PID 6136 | 1695137495719	WebDriver BiDi	DEBUG	Connection e8612ab7-7972-4a93-bd3c-5301c48313ef attached to session f03d5897-def0-4fa2-8a34-4c3d710f4ba1
[task 2023-09-19T15:31:35.735Z] 15:31:35     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/bidi/input/perform_actions/invalid.py::test_params_origin_element_element_invalid_type[False-pointer] 
[task 2023-09-19T15:31:35.735Z] 15:31:35     INFO - PID 6136 | 1695137495723	webdriver::server	DEBUG	-> POST /session/f03d5897-def0-4fa2-8a34-4c3d710f4ba1/window/rect {"width": 800, "height": 600}
[task 2023-09-19T15:31:35.736Z] 15:31:35     INFO - PID 6136 | 1695137495726	Marionette	DEBUG	0 -> [0,7602,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2023-09-19T15:31:35.737Z] 15:31:35     INFO - PID 6136 | 1695137495728	Marionette	TRACE	Requested window geometry matches
[task 2023-09-19T15:31:35.738Z] 15:31:35     INFO - PID 6136 | 1695137495730	Marionette	DEBUG	0 <- [1,7602,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2023-09-19T15:31:35.739Z] 15:31:35     INFO - PID 6136 | 1695137495731	webdriver::server	DEBUG	<- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2023-09-19T15:31:35.739Z] 15:31:35     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/input/perform_actions/invalid.py | expected OK
[task 2023-09-19T15:31:35.739Z] 15:31:35     INFO - TEST-INFO took 30016ms
[task 2023-09-19T15:31:36.027Z] 15:31:36     INFO - Test harness output was not a valid structured log message
[task 2023-09-19T15:31:36.027Z] 15:31:36     INFO - Exception ignored in: <coroutine object WebSocketCommonProtocol.close_connection at 0x7f8fc38e3560>
[task 2023-09-19T15:31:36.027Z] 15:31:36     INFO - Traceback (most recent call last):
[task 2023-09-19T15:31:36.027Z] 15:31:36     INFO -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/third_party/websockets/src/websockets/legacy/protocol.py", line 1329, in close_connection
[task 2023-09-19T15:31:36.027Z] 15:31:36     INFO -     await self.close_transport()
[task 2023-09-19T15:31:36.027Z] 15:31:36     INFO -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/third_party/websockets/src/websockets/legacy/protocol.py", line 1345, in close_transport
[task 2023-09-19T15:31:36.027Z] 15:31:36     INFO -     self.transport.close()
[task 2023-09-19T15:31:36.028Z] 15:31:36     INFO -   File "/usr/lib/python3.7/asyncio/selector_events.py", line 653, in close
[task 2023-09-19T15:31:36.028Z] 15:31:36     INFO -     self._loop.call_soon(self._call_connection_lost, None)
[task 2023-09-19T15:31:36.028Z] 15:31:36     INFO -   File "/usr/lib/python3.7/asyncio/base_events.py", line 683, in call_soon
[task 2023-09-19T15:31:36.028Z] 15:31:36     INFO -     self._check_closed()
[task 2023-09-19T15:31:36.028Z] 15:31:36     INFO -   File "/usr/lib/python3.7/asyncio/base_events.py", line 475, in _check_closed
[task 2023-09-19T15:31:36.028Z] 15:31:36     INFO -     raise RuntimeError('Event loop is closed')
[task 2023-09-19T15:31:36.028Z] 15:31:36     INFO - RuntimeError: Event loop is closed
[task 2023-09-19T15:31:36.066Z] 15:31:36     INFO - Closing logging queue
[task 2023-09-19T15:31:36.066Z] 15:31:36     INFO - queue closed
[task 2023-09-19T15:31:36.072Z] 15:31:36     INFO - PID 6426 | 1695137496071	geckodriver	INFO	Listening on 127.0.0.1:42621
[task 2023-09-19T15:31:36.073Z] 15:31:36     INFO - Starting runner

It's coming from Bug 1824227 - retriggers.

Flags: needinfo?(hskupin)
Keywords: regression
Regressed by: 1824227

Set release status flags based on info from the regressing bug 1824227

The problem here is that this test now has too many individual sub tests. I would propose that we simply extend the timeout given that it would be good to have all the cases in a single file. I'll provide a patch.

Flags: needinfo?(hskupin)
Assignee: nobody → hskupin
Status: NEW → ASSIGNED
Points: --- → 1
Priority: P5 → P1
Whiteboard: [webdriver:m8]
Pushed by hskupin@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/41a2229339cd
[wdspec] Extend timeout for /webdriver/tests/bidi/input/perform_actions/invalid.py. r=webdriver-reviewers,Sasha
Created web-platform-tests PR https://github.com/web-platform-tests/wpt/pull/42081 for changes under testing/web-platform/tests
Whiteboard: [webdriver:m8] → [webdriver:m8], [wptsync upstream]
Status: ASSIGNED → RESOLVED
Closed: 8 months ago
Resolution: --- → FIXED
Target Milestone: --- → 119 Branch

(In reply to Web Platform Test Sync Bot (Matrix: #interop:mozilla.org) from comment #6)

Created web-platform-tests PR
https://github.com/web-platform-tests/wpt/pull/42081 for changes under
testing/web-platform/tests

The upstream PR has now been merged.

You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: