Closed Bug 1876791 Opened 5 months ago Closed 3 months ago

Intermittent [tier 2] /webdriver/tests/bidi/browsing_context/activate/activate.py | single tracking bug

Categories

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

defect

Tracking

(Not tracked)

RESOLVED WORKSFORME

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, intermittent-testcase)

Attachments

(1 obsolete file)

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


[task 2024-01-26T15:18:33.752Z] 15:18:33     INFO - STDOUT: PASSED
[task 2024-01-26T15:18:33.752Z] 15:18:33     INFO - PID 1175 | 1706282313733	RemoteAgent	DEBUG	WebDriverBiDiConnection 840e04be-f434-4f82-8aa4-cf0ee3dd6b26 closed
[task 2024-01-26T15:18:33.753Z] 15:18:33     INFO - PID 1175 | 1706282313735	webdriver::server	DEBUG	-> POST /session/699f8b69-45d3-49b6-8037-a11c442a1cd8/timeouts {"implicit": 0}
[task 2024-01-26T15:18:33.757Z] 15:18:33     INFO - PID 1175 | 1706282313736	Marionette	DEBUG	0 -> [0,68,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2024-01-26T15:18:33.757Z] 15:18:33     INFO - PID 1175 | 1706282313736	Marionette	DEBUG	0 <- [1,68,null,{"value":null}]
[task 2024-01-26T15:18:33.766Z] 15:18:33     INFO - PID 1175 | 1706282313737	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-01-26T15:18:33.766Z] 15:18:33     INFO - PID 1175 | 1706282313738	webdriver::server	DEBUG	-> POST /session/699f8b69-45d3-49b6-8037-a11c442a1cd8/timeouts {"pageLoad": 300000}
[task 2024-01-26T15:18:33.766Z] 15:18:33     INFO - PID 1175 | 1706282313739	Marionette	DEBUG	0 -> [0,69,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2024-01-26T15:18:33.766Z] 15:18:33     INFO - PID 1175 | 1706282313739	Marionette	DEBUG	0 <- [1,69,null,{"value":null}]
[task 2024-01-26T15:18:33.766Z] 15:18:33     INFO - PID 1175 | 1706282313740	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-01-26T15:18:33.766Z] 15:18:33     INFO - PID 1175 | 1706282313742	webdriver::server	DEBUG	-> POST /session/699f8b69-45d3-49b6-8037-a11c442a1cd8/timeouts {"script": 30000}
[task 2024-01-26T15:18:33.766Z] 15:18:33     INFO - PID 1175 | 1706282313742	Marionette	DEBUG	0 -> [0,70,"WebDriver:SetTimeouts",{"script":30000}]
[task 2024-01-26T15:18:33.774Z] 15:18:33     INFO - PID 1175 | 1706282313742	Marionette	DEBUG	0 <- [1,70,null,{"value":null}]
[task 2024-01-26T15:18:33.774Z] 15:18:33     INFO - PID 1175 | 1706282313743	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-01-26T15:18:33.774Z] 15:18:33     INFO - PID 1175 | 1706282313745	webdriver::server	DEBUG	-> GET /session/699f8b69-45d3-49b6-8037-a11c442a1cd8/window
[task 2024-01-26T15:18:33.774Z] 15:18:33     INFO - PID 1175 | 1706282313745	Marionette	DEBUG	0 -> [0,71,"WebDriver:GetWindowHandle",{}]
[task 2024-01-26T15:18:33.774Z] 15:18:33     INFO - PID 1175 | 1706282313745	Marionette	DEBUG	0 <- [1,71,null,{"value":"3476c5b0-2d83-4867-af27-b3bae5a52826"}]
[task 2024-01-26T15:18:33.774Z] 15:18:33     INFO - PID 1175 | 1706282313746	webdriver::server	DEBUG	<- 200 OK {"value":"3476c5b0-2d83-4867-af27-b3bae5a52826"}
[task 2024-01-26T15:18:33.774Z] 15:18:33     INFO - PID 1175 | 1706282313747	webdriver::server	DEBUG	-> GET /session/699f8b69-45d3-49b6-8037-a11c442a1cd8/window
[task 2024-01-26T15:18:33.774Z] 15:18:33     INFO - PID 1175 | 1706282313747	Marionette	DEBUG	0 -> [0,72,"WebDriver:GetWindowHandle",{}]
[task 2024-01-26T15:18:33.774Z] 15:18:33     INFO - PID 1175 | 1706282313747	Marionette	DEBUG	0 <- [1,72,null,{"value":"3476c5b0-2d83-4867-af27-b3bae5a52826"}]
[task 2024-01-26T15:18:33.774Z] 15:18:33     INFO - PID 1175 | 1706282313748	webdriver::server	DEBUG	<- 200 OK {"value":"3476c5b0-2d83-4867-af27-b3bae5a52826"}
[task 2024-01-26T15:18:33.787Z] 15:18:33     INFO - PID 1175 | 1706282313749	webdriver::server	DEBUG	-> GET /session/699f8b69-45d3-49b6-8037-a11c442a1cd8/window/handles
[task 2024-01-26T15:18:33.787Z] 15:18:33     INFO - PID 1175 | 1706282313751	Marionette	DEBUG	0 -> [0,73,"WebDriver:GetWindowHandles",{}]
[task 2024-01-26T15:18:33.787Z] 15:18:33     INFO - PID 1175 | 1706282313751	Marionette	DEBUG	0 <- [1,73,null,["3476c5b0-2d83-4867-af27-b3bae5a52826","07592a5f-6f86-489a-86da-e5a24b0f824a","efd0cfea-6a80-4b29-935f-cdfc60773fcd"]]
[task 2024-01-26T15:18:33.787Z] 15:18:33     INFO - PID 1175 | 1706282313753	webdriver::server	DEBUG	<- 200 OK {"value":["3476c5b0-2d83-4867-af27-b3bae5a52826","07592a5f-6f86-489a-86da-e5a24b0f824a","efd0cfea-6a80-4b29-935f-cdfc60773fcd"]}
[task 2024-01-26T15:18:33.792Z] 15:18:33     INFO - PID 1175 | 1706282313755	webdriver::server	DEBUG	-> POST /session/699f8b69-45d3-49b6-8037-a11c442a1cd8/window {"handle": "3476c5b0-2d83-4867-af27-b3bae5a52826"}
[task 2024-01-26T15:18:33.792Z] 15:18:33     INFO - PID 1175 | 1706282313762	Marionette	DEBUG	0 -> [0,74,"WebDriver:SwitchToWindow",{"handle":"3476c5b0-2d83-4867-af27-b3bae5a52826"}]
[task 2024-01-26T15:18:33.792Z] 15:18:33     INFO - PID 1175 | 1706282313766	RemoteAgent	TRACE	Received DOM event activate for [object Window]
[task 2024-01-26T15:18:33.792Z] 15:18:33     INFO - PID 1175 | 1706282313766	RemoteAgent	TRACE	Received DOM event focus for [object HTMLDocument]
[task 2024-01-26T15:18:33.795Z] 15:18:33     INFO - PID 1175 | 1706282313768	Marionette	DEBUG	0 <- [1,74,null,{"value":null}]
[task 2024-01-26T15:18:33.795Z] 15:18:33     INFO - PID 1175 | 1706282313774	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-01-26T15:18:33.795Z] 15:18:33     INFO - PID 1175 | 1706282313777	webdriver::server	DEBUG	-> POST /session/699f8b69-45d3-49b6-8037-a11c442a1cd8/alert/dismiss {}
[task 2024-01-26T15:18:33.799Z] 15:18:33     INFO - PID 1175 | 1706282313791	Marionette	DEBUG	0 -> [0,75,"WebDriver:DismissAlert",{}]
<...>
[task 2024-01-26T15:18:34.083Z] 15:18:34     INFO - PID 1175 | 1706282314074	RemoteAgent	DEBUG	Resetting recommended pref network.cookie.cookieBehavior
[task 2024-01-26T15:18:34.084Z] 15:18:34     INFO - PID 1175 | 1706282314074	RemoteAgent	TRACE	Received observer notification quit-application
[task 2024-01-26T15:18:34.085Z] 15:18:34     INFO - PID 1175 | 1706282314075	Marionette	DEBUG	Marionette stopped listening
[task 2024-01-26T15:18:34.085Z] 15:18:34     INFO - PID 1175 | 1706282314075	Marionette	DEBUG	0 <- [1,92,null,{"cause":"shutdown","forced":false,"in_app":true}]
[task 2024-01-26T15:18:34.086Z] 15:18:34     INFO - PID 1175 | 1706282314084	RemoteAgent	TRACE	MessageHandler WINDOW_GLOBAL for session 699f8b69-45d3-49b6-8037-a11c442a1cd8 is being destroyed
[task 2024-01-26T15:18:34.087Z] 15:18:34     INFO - PID 1175 | 1706282314084	RemoteAgent	TRACE	Unregistered MessageHandler WINDOW_GLOBAL for session 699f8b69-45d3-49b6-8037-a11c442a1cd8
[task 2024-01-26T15:18:34.087Z] 15:18:34     INFO - PID 1175 | 1706282314084	RemoteAgent	DEBUG	Resetting recommended pref browser.contentblocking.features.standard
[task 2024-01-26T15:18:34.088Z] 15:18:34     INFO - PID 1175 | 1706282314084	RemoteAgent	DEBUG	Resetting recommended pref network.cookie.cookieBehavior
[task 2024-01-26T15:18:34.089Z] 15:18:34     INFO - PID 1175 | 1706282314085	webdriver::server	DEBUG	Teardown session
[task 2024-01-26T15:18:34.089Z] 15:18:34     INFO - PID 1175 | 1706282314088	Marionette	DEBUG	Closed connection 0
[task 2024-01-26T15:18:34.260Z] 15:18:34     INFO - PID 1175 | 1706282314259	geckodriver::browser	DEBUG	Browser process stopped: exit status: 0
[task 2024-01-26T15:18:34.260Z] 15:18:34     INFO - PID 1175 | 1706282314259	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-01-26T15:18:34.263Z] 15:18:34     INFO - STDOUT: =================================== FAILURES ===================================
[task 2024-01-26T15:18:34.264Z] 15:18:34     INFO - STDOUT: ___________________________ test_keeps_focused_area ____________________________
[task 2024-01-26T15:18:34.264Z] 15:18:34     INFO - STDOUT: bidi_session = <webdriver.bidi.client.BidiSession object at 0x7f89ba450f90>
[task 2024-01-26T15:18:34.264Z] 15:18:34     INFO - STDOUT: inline = <function inline.<locals>.inline at 0x7f89ba471f80>
[task 2024-01-26T15:18:34.265Z] 15:18:34     INFO - STDOUT: new_tab = {'context': 'c529c757-0cce-47ec-96bf-d46089057f73'}
[task 2024-01-26T15:18:34.266Z] 15:18:34     INFO - STDOUT: top_context = {'children': [], 'context': '3476c5b0-2d83-4867-af27-b3bae5a52826', 'parent': None, 'url': 'about:blank'}
[task 2024-01-26T15:18:34.266Z] 15:18:34     INFO - STDOUT:     async def test_keeps_focused_area(bidi_session, inline, new_tab, top_context):
[task 2024-01-26T15:18:34.267Z] 15:18:34     INFO - STDOUT:         await bidi_session.browsing_context.activate(context=new_tab["context"])
[task 2024-01-26T15:18:34.267Z] 15:18:34     INFO - STDOUT:         assert await get_visibility_state(bidi_session, new_tab) == 'visible'
[task 2024-01-26T15:18:34.268Z] 15:18:34     INFO - STDOUT:         assert await get_document_focus(bidi_session, new_tab) is True
[task 2024-01-26T15:18:34.268Z] 15:18:34     INFO - STDOUT:     
[task 2024-01-26T15:18:34.269Z] 15:18:34     INFO - STDOUT:         await bidi_session.browsing_context.navigate(context=new_tab["context"],
[task 2024-01-26T15:18:34.269Z] 15:18:34     INFO - STDOUT:                                                      url=inline("<textarea autofocus></textarea><input>"),
[task 2024-01-26T15:18:34.270Z] 15:18:34     INFO - STDOUT:                                                      wait="complete")
[task 2024-01-26T15:18:34.270Z] 15:18:34     INFO - STDOUT:     
[task 2024-01-26T15:18:34.271Z] 15:18:34     INFO - STDOUT:         await bidi_session.script.evaluate(
[task 2024-01-26T15:18:34.271Z] 15:18:34     INFO - STDOUT:             expression="""document.querySelector("input").focus()""",
[task 2024-01-26T15:18:34.272Z] 15:18:34     INFO - STDOUT:             target=ContextTarget(new_tab["context"]),
[task 2024-01-26T15:18:34.272Z] 15:18:34     INFO - STDOUT:             await_promise=False)
[task 2024-01-26T15:18:34.273Z] 15:18:34     INFO - STDOUT:     
[task 2024-01-26T15:18:34.273Z] 15:18:34     INFO - STDOUT:         assert await is_selector_focused(bidi_session, new_tab, "input")
[task 2024-01-26T15:18:34.274Z] 15:18:34     INFO - STDOUT:     
[task 2024-01-26T15:18:34.274Z] 15:18:34     INFO - STDOUT:         await bidi_session.browsing_context.activate(context=top_context["context"])
[task 2024-01-26T15:18:34.275Z] 15:18:34     INFO - STDOUT: >       assert await get_document_focus(bidi_session, new_tab) is False
[task 2024-01-26T15:18:34.275Z] 15:18:34     INFO - STDOUT: E       assert True is False
[task 2024-01-26T15:18:34.275Z] 15:18:34     INFO - STDOUT: bidi_session = <webdriver.bidi.client.BidiSession object at 0x7f89ba450f90>
[task 2024-01-26T15:18:34.276Z] 15:18:34     INFO - STDOUT: inline     = <function inline.<locals>.inline at 0x7f89ba471f80>
[task 2024-01-26T15:18:34.276Z] 15:18:34     INFO - STDOUT: new_tab    = {'context': 'c529c757-0cce-47ec-96bf-d46089057f73'}
[task 2024-01-26T15:18:34.277Z] 15:18:34     INFO - STDOUT: top_context = {'children': [],
[task 2024-01-26T15:18:34.277Z] 15:18:34     INFO -  'context': '3476c5b0-2d83-4867-af27-b3bae5a52826',
[task 2024-01-26T15:18:34.277Z] 15:18:34     INFO -  'parent': None,
[task 2024-01-26T15:18:34.277Z] 15:18:34     INFO -  'url': 'about:blank'}
[task 2024-01-26T15:18:34.278Z] 15:18:34     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/bidi/browsing_context/activate/activate.py
[task 2024-01-26T15:18:34.278Z] 15:18:34     INFO - STDOUT: :52: AssertionError
[task 2024-01-26T15:18:34.279Z] 15:18:34     INFO - STDOUT: =========================== short test summary info ============================
[task 2024-01-26T15:18:34.279Z] 15:18:34     INFO - STDOUT: FAILED tests/web-platform/tests/webdriver/tests/bidi/browsing_context/activate/activate.py::test_keeps_focused_area
[task 2024-01-26T15:18:34.280Z] 15:18:34     INFO - STDOUT: ========================= 1 failed, 4 passed in 4.38s ==========================
[task 2024-01-26T15:18:34.282Z] 15:18:34     INFO - 
[task 2024-01-26T15:18:34.282Z] 15:18:34     INFO - TEST-PASS | /webdriver/tests/bidi/browsing_context/activate/activate.py | test_activate 
[task 2024-01-26T15:18:34.282Z] 15:18:34     INFO - TEST-PASS | /webdriver/tests/bidi/browsing_context/activate/activate.py | test_deactivates_other_contexts 
[task 2024-01-26T15:18:34.282Z] 15:18:34     INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/bidi/browsing_context/activate/activate.py | test_keeps_focused_area - assert True is False
[task 2024-01-26T15:18:34.282Z] 15:18:34     INFO - bidi_session = <webdriver.bidi.client.BidiSession object at 0x7f89ba450f90>
[task 2024-01-26T15:18:34.282Z] 15:18:34     INFO - inline = <function inline.<locals>.inline at 0x7f89ba471f80>
[task 2024-01-26T15:18:34.282Z] 15:18:34     INFO - new_tab = {'context': 'c529c757-0cce-47ec-96bf-d46089057f73'}
[task 2024-01-26T15:18:34.282Z] 15:18:34     INFO - top_context = {'children': [], 'context': '3476c5b0-2d83-4867-af27-b3bae5a52826', 'parent': None, 'url': 'about:blank'}
[task 2024-01-26T15:18:34.282Z] 15:18:34     INFO - 
[task 2024-01-26T15:18:34.282Z] 15:18:34     INFO -     async def test_keeps_focused_area(bidi_session, inline, new_tab, top_context):
[task 2024-01-26T15:18:34.282Z] 15:18:34     INFO -         await bidi_session.browsing_context.activate(context=new_tab["context"])
[task 2024-01-26T15:18:34.282Z] 15:18:34     INFO -         assert await get_visibility_state(bidi_session, new_tab) == 'visible'
[task 2024-01-26T15:18:34.282Z] 15:18:34     INFO -         assert await get_document_focus(bidi_session, new_tab) is True
[task 2024-01-26T15:18:34.282Z] 15:18:34     INFO -     
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO -         await bidi_session.browsing_context.navigate(context=new_tab["context"],
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO -                                                      url=inline("<textarea autofocus></textarea><input>"),
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO -                                                      wait="complete")
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO -     
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO -         await bidi_session.script.evaluate(
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO -             expression="""document.querySelector("input").focus()""",
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO -             target=ContextTarget(new_tab["context"]),
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO -             await_promise=False)
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO -     
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO -         assert await is_selector_focused(bidi_session, new_tab, "input")
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO -     
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO -         await bidi_session.browsing_context.activate(context=top_context["context"])
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO - >       assert await get_document_focus(bidi_session, new_tab) is False
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO - E       assert True is False
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO - 
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO - bidi_session = <webdriver.bidi.client.BidiSession object at 0x7f89ba450f90>
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO - inline     = <function inline.<locals>.inline at 0x7f89ba471f80>
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO - new_tab    = {'context': 'c529c757-0cce-47ec-96bf-d46089057f73'}
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO - top_context = {'children': [],
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO -  'context': '3476c5b0-2d83-4867-af27-b3bae5a52826',
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO -  'parent': None,
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO -  'url': 'about:blank'}
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO - 
[task 2024-01-26T15:18:34.283Z] 15:18:34     INFO - tests/web-platform/tests/webdriver/tests/bidi/browsing_context/activate/activate.py:52: AssertionError
[task 2024-01-26T15:18:34.284Z] 15:18:34     INFO - ..
[task 2024-01-26T15:18:34.284Z] 15:18:34     INFO - TEST-OK | /webdriver/tests/bidi/browsing_context/activate/activate.py | took 4604ms
[task 2024-01-26T15:18:34.286Z] 15:18:34     INFO - PID 1175 | 1706282314268	webdriver::server	DEBUG	-> GET /status
[task 2024-01-26T15:18:34.287Z] 15:18:34     INFO - PID 1175 | 1706282314268	webdriver::server	DEBUG	<- 200 OK {"value":{"message":"","ready":true}}
[task 2024-01-26T15:18:34.323Z] 15:18:34     INFO - Closing logging queue
[task 2024-01-26T15:18:34.323Z] 15:18:34     INFO - queue closed
[task 2024-01-26T15:18:34.432Z] 15:18:34     INFO - PID 1196 | 1706282314331	geckodriver	INFO	Listening on 127.0.0.1:49449
[task 2024-01-26T15:18:34.432Z] 15:18:34     INFO - Starting runner
[task 2024-01-26T15:18:34.786Z] 15:18:34     INFO - TEST-START | /webdriver/tests/bidi/browsing_context/activate/invalid.py

This might be related to bug 1876604.

See Also: → 1876604
Attachment #9385398 - Attachment is obsolete: true

This failure should be hopefully fixed now with the landing of bug 1877469.

Status: NEW → RESOLVED
Closed: 3 months ago
Resolution: --- → WORKSFORME
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: