Closed Bug 1824958 Opened 2 years ago Closed 2 years ago

Intermittent /webdriver/tests/bidi/log/entry_added/subscription.py | test_subscribe_twice[console_api_log] - webdriver.bidi.error.UnknownErrorException: unknown error (AbortError: Actor 'MessageHandlerFrame' destroyed before query 'MessageHandlerFrame

Categories

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

defect

Tracking

(Not tracked)

RESOLVED DUPLICATE of bug 1767226

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2023-03-28T09:15:57.567Z] 09:15:57     INFO - STDOUT: _________________ test_subscribe_unsubscribe[javascript_error] _________________
[task 2023-03-28T09:15:57.568Z] 09:15:57     INFO - STDOUT: bidi_session = <webdriver.bidi.client.BidiSession object at 0x7f7634318050>
[task 2023-03-28T09:15:57.568Z] 09:15:57     INFO - STDOUT: new_tab = {'context': '036fc214-2c1a-42e6-8935-658f5852f2ac'}
[task 2023-03-28T09:15:57.568Z] 09:15:57     INFO - STDOUT: wait_for_event = <function wait_for_event.<locals>.wait_for_event at 0x7f763489c710>
[task 2023-03-28T09:15:57.569Z] 09:15:57     INFO - STDOUT: log_type = 'javascript_error'
[task 2023-03-28T09:15:57.569Z] 09:15:57     INFO - STDOUT:     @pytest.mark.asyncio
[task 2023-03-28T09:15:57.570Z] 09:15:57     INFO - STDOUT:     @pytest.mark.parametrize("log_type", ["console_api_log", "javascript_error"])
[task 2023-03-28T09:15:57.570Z] 09:15:57     INFO - STDOUT:     async def test_subscribe_unsubscribe(bidi_session, new_tab, wait_for_event, log_type):
[task 2023-03-28T09:15:57.571Z] 09:15:57     INFO - STDOUT:         # Subscribe for log events globally
[task 2023-03-28T09:15:57.571Z] 09:15:57     INFO - STDOUT:         await bidi_session.session.subscribe(events=["log.entryAdded"])
[task 2023-03-28T09:15:57.572Z] 09:15:57     INFO - STDOUT:     
[task 2023-03-28T09:15:57.572Z] 09:15:57     INFO - STDOUT:         on_entry_added = wait_for_event("log.entryAdded")
[task 2023-03-28T09:15:57.572Z] 09:15:57     INFO - STDOUT:         await create_log(bidi_session, new_tab, log_type, "some text")
[task 2023-03-28T09:15:57.573Z] 09:15:57     INFO - STDOUT:         await on_entry_added
[task 2023-03-28T09:15:57.573Z] 09:15:57     INFO - STDOUT:     
[task 2023-03-28T09:15:57.574Z] 09:15:57     INFO - STDOUT:         # Unsubscribe from log events globally
[task 2023-03-28T09:15:57.574Z] 09:15:57     INFO - STDOUT:         await bidi_session.session.unsubscribe(events=["log.entryAdded"])
[task 2023-03-28T09:15:57.575Z] 09:15:57     INFO - STDOUT:     
[task 2023-03-28T09:15:57.575Z] 09:15:57     INFO - STDOUT:         # Track all received log.entryAdded events in the events array
[task 2023-03-28T09:15:57.575Z] 09:15:57     INFO - STDOUT:         events = []
[task 2023-03-28T09:15:57.576Z] 09:15:57     INFO - STDOUT:     
[task 2023-03-28T09:15:57.576Z] 09:15:57     INFO - STDOUT:         async def on_event(method, data):
[task 2023-03-28T09:15:57.577Z] 09:15:57     INFO - STDOUT:             events.append(data)
[task 2023-03-28T09:15:57.577Z] 09:15:57     INFO - STDOUT:     
[task 2023-03-28T09:15:57.577Z] 09:15:57     INFO - STDOUT:         remove_listener = bidi_session.add_event_listener("log.entryAdded", on_event)
[task 2023-03-28T09:15:57.578Z] 09:15:57     INFO - STDOUT:     
[task 2023-03-28T09:15:57.578Z] 09:15:57     INFO - STDOUT:         expected_text_0 = await create_log(bidi_session, new_tab, log_type, "text_0")
[task 2023-03-28T09:15:57.579Z] 09:15:57     INFO - STDOUT:     
[task 2023-03-28T09:15:57.579Z] 09:15:57     INFO - STDOUT:         # Wait for some time before checking the events array
[task 2023-03-28T09:15:57.580Z] 09:15:57     INFO - STDOUT:         await asyncio.sleep(0.5)
[task 2023-03-28T09:15:57.580Z] 09:15:57     INFO - STDOUT:         assert len(events) == 0
[task 2023-03-28T09:15:57.580Z] 09:15:57     INFO - STDOUT:     
[task 2023-03-28T09:15:57.581Z] 09:15:57     INFO - STDOUT:         # Refresh to create a new context
[task 2023-03-28T09:15:57.581Z] 09:15:57     INFO - STDOUT:         await bidi_session.browsing_context.navigate(
[task 2023-03-28T09:15:57.582Z] 09:15:57     INFO - STDOUT: >           context=new_tab["context"], url=new_tab["url"], wait="complete"
[task 2023-03-28T09:15:57.582Z] 09:15:57     INFO - STDOUT:         )
[task 2023-03-28T09:15:57.583Z] 09:15:57     INFO - STDOUT: E       KeyError: 'url'
[task 2023-03-28T09:15:57.583Z] 09:15:57     INFO - STDOUT: bidi_session = <webdriver.bidi.client.BidiSession object at 0x7f7634318050>
[task 2023-03-28T09:15:57.583Z] 09:15:57     INFO - STDOUT: events     = []
[task 2023-03-28T09:15:57.587Z] 09:15:57     INFO - 
[task 2023-03-28T09:15:57.587Z] 09:15:57     INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/bidi/log/entry_added/subscription.py | test_subscribe_twice[console_api_log] - webdriver.bidi.error.UnknownErrorException: unknown error (AbortError: Actor 'MessageHandlerFrame' destroyed before query 'MessageHandlerFrameParent:sendCommand' was resolved)
[task 2023-03-28T09:15:57.587Z] 09:15:57     INFO - bidi_session = <webdriver.bidi.client.BidiSession object at 0x7f7634318050>
[task 2023-03-28T09:15:57.587Z] 09:15:57     INFO - new_tab = {'context': '17647800-f557-4540-82a6-8a0db5400b2a'}
[task 2023-03-28T09:15:57.587Z] 09:15:57     INFO - wait_for_event = <function wait_for_event.<locals>.wait_for_event at 0x7f76347bedd0>
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO - log_type = 'console_api_log'
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO - 
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO -     @pytest.mark.asyncio
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO -     @pytest.mark.parametrize("log_type", ["console_api_log", "javascript_error"])
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO -     async def test_subscribe_twice(bidi_session, new_tab, wait_for_event, log_type):
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO -         # Subscribe to log.entryAdded twice and check that events are received once.
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO -         await bidi_session.session.subscribe(events=["log.entryAdded"])
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO -         await bidi_session.session.subscribe(events=["log.entryAdded"])
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO -     
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO -         # Track all received log.entryAdded events in the events array
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO -         events = []
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO -     
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO -         async def on_event(method, data):
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO -             events.append(data)
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO -     
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO -         remove_listener = bidi_session.add_event_listener("log.entryAdded", on_event)
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO -     
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO -         # Check for a ConsoleLogEntry.
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO -         on_entry_added = wait_for_event("log.entryAdded")
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO - >       expected_text = await create_log(bidi_session, new_tab, log_type, "text1")
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO - 
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO - bidi_session = <webdriver.bidi.client.BidiSession object at 0x7f7634318050>
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO - events     = []
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO - log_type   = 'console_api_log'
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO - new_tab    = {'context': '17647800-f557-4540-82a6-8a0db5400b2a'}
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO - on_entry_added = <Future pending>
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO - on_event   = <function test_subscribe_twice.<locals>.on_event at 0x7f76347be4d0>
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO - remove_listener = <function BidiSession.add_event_listener.<locals>.<lambda> at 0x7f76347be830>
[task 2023-03-28T09:15:57.588Z] 09:15:57     INFO - wait_for_event = <function wait_for_event.<locals>.wait_for_event at 0x7f76347bedd0>
[task 2023-03-28T09:15:57.589Z] 09:15:57     INFO - 
[task 2023-03-28T09:15:57.589Z] 09:15:57     INFO - tests/web-platform/tests/webdriver/tests/bidi/log/entry_added/subscription.py:25: 
[task 2023-03-28T09:15:57.589Z] 09:15:57     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
Status: NEW → RESOLVED
Closed: 2 years ago
Duplicate of bug: 1767226
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.