Closed Bug 1852250 Opened 8 months ago Closed 8 months ago

Intermittent /webdriver/tests/bidi/network/response_started/response_started.py | test_load_page_twice - AssertionError (assert event["navigation"] == navigation

Categories

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

Firefox 119
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: jdescottes)

References

(Regression)

Details

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

Attachments

(1 file)

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


[task 2023-09-08T10:22:11.026Z] 10:22:11     INFO - PID 13470 | 1694168531024	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-09-08T10:22:11.033Z] 10:22:11     INFO - STDOUT: =================================== FAILURES ===================================
[task 2023-09-08T10:22:11.034Z] 10:22:11     INFO - STDOUT: _____________________________ test_load_page_twice _____________________________
[task 2023-09-08T10:22:11.034Z] 10:22:11     INFO - STDOUT: bidi_session = <webdriver.bidi.client.BidiSession object at 0x7f2845213090>
[task 2023-09-08T10:22:11.035Z] 10:22:11     INFO - STDOUT: top_context = {'children': [], 'context': '93ffdd66-53a5-470b-b24a-f1a607ef97f0', 'parent': None, 'url': 'https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html'}
[task 2023-09-08T10:22:11.036Z] 10:22:11     INFO - STDOUT: wait_for_event = <function wait_for_event.<locals>.wait_for_event at 0x7f28449dee60>
[task 2023-09-08T10:22:11.036Z] 10:22:11     INFO - STDOUT: url = <function url.<locals>.url at 0x7f28449def80>
[task 2023-09-08T10:22:11.037Z] 10:22:11     INFO - STDOUT: setup_network_test = <function setup_network_test.<locals>._setup_network_test at 0x7f284493b5f0>
[task 2023-09-08T10:22:11.037Z] 10:22:11     INFO - STDOUT:     @pytest.mark.asyncio
[task 2023-09-08T10:22:11.038Z] 10:22:11     INFO - STDOUT:     async def test_load_page_twice(
[task 2023-09-08T10:22:11.039Z] 10:22:11     INFO - STDOUT:         bidi_session, top_context, wait_for_event, url, setup_network_test
[task 2023-09-08T10:22:11.039Z] 10:22:11     INFO - STDOUT:     ):
[task 2023-09-08T10:22:11.040Z] 10:22:11     INFO - STDOUT:         html_url = url(PAGE_EMPTY_HTML)
[task 2023-09-08T10:22:11.040Z] 10:22:11     INFO - STDOUT:     
[task 2023-09-08T10:22:11.041Z] 10:22:11     INFO - STDOUT:         network_events = await setup_network_test(events=[RESPONSE_STARTED_EVENT])
[task 2023-09-08T10:22:11.041Z] 10:22:11     INFO - STDOUT:         events = network_events[RESPONSE_STARTED_EVENT]
[task 2023-09-08T10:22:11.042Z] 10:22:11     INFO - STDOUT:     
[task 2023-09-08T10:22:11.042Z] 10:22:11     INFO - STDOUT:         on_response_started = wait_for_event(RESPONSE_STARTED_EVENT)
[task 2023-09-08T10:22:11.043Z] 10:22:11     INFO - STDOUT:         result = await bidi_session.browsing_context.navigate(
[task 2023-09-08T10:22:11.044Z] 10:22:11     INFO - STDOUT:             context=top_context["context"],
[task 2023-09-08T10:22:11.045Z] 10:22:11     INFO - STDOUT:             url=html_url,
[task 2023-09-08T10:22:11.045Z] 10:22:11     INFO - STDOUT:             wait="complete",
[task 2023-09-08T10:22:11.045Z] 10:22:11     INFO - STDOUT:         )
[task 2023-09-08T10:22:11.046Z] 10:22:11     INFO - STDOUT:         await on_response_started
[task 2023-09-08T10:22:11.046Z] 10:22:11     INFO - STDOUT:     
[task 2023-09-08T10:22:11.047Z] 10:22:11     INFO - STDOUT:         assert len(events) == 1
[task 2023-09-08T10:22:11.047Z] 10:22:11     INFO - STDOUT:         expected_request = {"method": "GET", "url": html_url}
[task 2023-09-08T10:22:11.047Z] 10:22:11     INFO - STDOUT:         expected_response = {
[task 2023-09-08T10:22:11.048Z] 10:22:11     INFO - STDOUT:             "url": html_url,
[task 2023-09-08T10:22:11.066Z] 10:22:11     INFO - 
[task 2023-09-08T10:22:11.066Z] 10:22:11     INFO - TEST-PASS | /webdriver/tests/bidi/network/response_started/response_started.py | test_subscribe_status 
[task 2023-09-08T10:22:11.066Z] 10:22:11     INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/bidi/network/response_started/response_started.py | test_load_page_twice - AssertionError
[task 2023-09-08T10:22:11.066Z] 10:22:11     INFO - bidi_session = <webdriver.bidi.client.BidiSession object at 0x7f2845213090>
[task 2023-09-08T10:22:11.066Z] 10:22:11     INFO - top_context = {'children': [], 'context': '93ffdd66-53a5-470b-b24a-f1a607ef97f0', 'parent': None, 'url': 'https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html'}
[task 2023-09-08T10:22:11.066Z] 10:22:11     INFO - wait_for_event = <function wait_for_event.<locals>.wait_for_event at 0x7f28449dee60>
[task 2023-09-08T10:22:11.066Z] 10:22:11     INFO - url = <function url.<locals>.url at 0x7f28449def80>
[task 2023-09-08T10:22:11.066Z] 10:22:11     INFO - setup_network_test = <function setup_network_test.<locals>._setup_network_test at 0x7f284493b5f0>
[task 2023-09-08T10:22:11.066Z] 10:22:11     INFO - 
[task 2023-09-08T10:22:11.066Z] 10:22:11     INFO -     @pytest.mark.asyncio
[task 2023-09-08T10:22:11.066Z] 10:22:11     INFO -     async def test_load_page_twice(
[task 2023-09-08T10:22:11.066Z] 10:22:11     INFO -         bidi_session, top_context, wait_for_event, url, setup_network_test
[task 2023-09-08T10:22:11.066Z] 10:22:11     INFO -     ):
[task 2023-09-08T10:22:11.066Z] 10:22:11     INFO -         html_url = url(PAGE_EMPTY_HTML)
[task 2023-09-08T10:22:11.066Z] 10:22:11     INFO -     
[task 2023-09-08T10:22:11.066Z] 10:22:11     INFO -         network_events = await setup_network_test(events=[RESPONSE_STARTED_EVENT])
[task 2023-09-08T10:22:11.066Z] 10:22:11     INFO -         events = network_events[RESPONSE_STARTED_EVENT]
[task 2023-09-08T10:22:11.067Z] 10:22:11     INFO -     
[task 2023-09-08T10:22:11.067Z] 10:22:11     INFO -         on_response_started = wait_for_event(RESPONSE_STARTED_EVENT)
[task 2023-09-08T10:22:11.067Z] 10:22:11     INFO -         result = await bidi_session.browsing_context.navigate(
[task 2023-09-08T10:22:11.067Z] 10:22:11     INFO -             context=top_context["context"],
[task 2023-09-08T10:22:11.067Z] 10:22:11     INFO -             url=html_url,
[task 2023-09-08T10:22:11.067Z] 10:22:11     INFO -             wait="complete",
[task 2023-09-08T10:22:11.067Z] 10:22:11     INFO -         )
[task 2023-09-08T10:22:11.067Z] 10:22:11     INFO -         await on_response_started
[task 2023-09-08T10:22:11.067Z] 10:22:11     INFO -     
[task 2023-09-08T10:22:11.067Z] 10:22:11     INFO -         assert len(events) == 1
[task 2023-09-08T10:22:11.067Z] 10:22:11     INFO -         expected_request = {"method": "GET", "url": html_url}
[task 2023-09-08T10:22:11.067Z] 10:22:11     INFO -         expected_response = {
[task 2023-09-08T10:22:11.067Z] 10:22:11     INFO -             "url": html_url,
[task 2023-09-08T10:22:11.067Z] 10:22:11     INFO -             "fromCache": False,
[task 2023-09-08T10:22:11.067Z] 10:22:11     INFO -             "mimeType": "text/html",
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -             "status": 200,
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -             "statusText": "OK",
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -             "protocol": "http/1.1",
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -         }
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -         assert_response_event(
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -             events[0],
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -             expected_request=expected_request,
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -             expected_response=expected_response,
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -             navigation=result["navigation"],
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO - >           redirect_count=0,
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -         )
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO - 
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO - bidi_session = <webdriver.bidi.client.BidiSession object at 0x7f2845213090>
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO - events     = [{'context': '93ffdd66-53a5-470b-b24a-f1a607ef97f0',
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -   'isBlocked': False,
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -   'navigation': '6a7ade25-a97b-4e70-b524-2937d9cdf901',
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -   'redirectCount': 0,
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -   'request': {'bodySize': None,
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -               'cookies': [],
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -               'headers': [{'name': 'Host',
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -                            'value': {'type': 'string',
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -                                      'value': 'web-platform.test:8443'}},
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -                           {'name': 'User-Agent',
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -                            'value': {'type': 'string',
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -                                      'value': 'Mozilla/5.0 (X11; Linux x86_64; '
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -                                               'rv:109.0) Gecko/20100101 '
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -                                               'Firefox/119.0'}},
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -                           {'name': 'Accept',
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -                            'value': {'type': 'string',
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -                                      'value': 'text/html,application/xhtml+xml,application/xml;q=0.9,image/avif,image/webp,*/*;q=0.8'}},
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -                           {'name': 'Accept-Language',
[task 2023-09-08T10:22:11.068Z] 10:22:11     INFO -                            'value': {'type': 'string',
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                                      'value': 'en-US,en;q=0.5'}},
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                           {'name': 'Accept-Encoding',
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                            'value': {'type': 'string',
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                                      'value': 'gzip, deflate, br'}},
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                           {'name': 'Connection',
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                            'value': {'type': 'string', 'value': 'keep-alive'}},
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                           {'name': 'Upgrade-Insecure-Requests',
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                            'value': {'type': 'string', 'value': '1'}},
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                           {'name': 'Sec-Fetch-Dest',
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                            'value': {'type': 'string', 'value': 'document'}},
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                           {'name': 'Sec-Fetch-Mode',
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                            'value': {'type': 'string', 'value': 'navigate'}},
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                           {'name': 'Sec-Fetch-Site',
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                            'value': {'type': 'string', 'value': 'none'}},
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                           {'name': 'Sec-Fetch-User',
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                            'value': {'type': 'string', 'value': '?1'}}],
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -               'headersSize': 491,
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -               'method': 'GET',
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -               'request': '15',
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -               'timings': {'connectEnd': 1694168521785625,
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                           'connectStart': 1694168521779958,
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                           'dnsEnd': 1694168521779424,
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                           'dnsStart': 1694168521779372,
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                           'fetchStart': 1694168521779372,
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                           'redirectEnd': 0,
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                           'redirectStart': 0,
[task 2023-09-08T10:22:11.069Z] 10:22:11     INFO -                           'requestStart': 1694168521785682,
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                           'requestTime': 1694168521774726,
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                           'responseEnd': 1694168521786984,
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                           'responseStart': 1694168521785700,
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                           'timeOrigin': 0,
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                           'tlsEnd': 1694168521785625,
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                           'tlsStart': 1694168521780231},
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -               'url': 'https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html'},
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -   'response': {'bodySize': 0,
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                'bytesReceived': 116,
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                'content': {'size': None},
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                'fromCache': False,
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                'headers': [{'name': 'Content-Type',
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                             'value': {'type': 'string', 'value': 'text/html'}},
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                            {'name': 'Server',
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                             'value': {'type': 'string',
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                                       'value': 'BaseHTTP/0.6 Python/3.7.5'}},
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                            {'name': 'Date',
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                             'value': {'type': 'string',
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                                       'value': 'Fri, 08 Sep 2023 10:22:01 '
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                                                'GMT'}}],
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                'headersSize': 116,
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                'mimeType': 'text/html',
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                'protocol': 'http/1.1',
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                'status': 200,
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                'statusText': 'OK',
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -                'url': 'https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html'},
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -   'timestamp': 1694168521787}]
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO - expected_request = {'method': 'GET',
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -  'url': 'https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html'}
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO - expected_response = {'fromCache': False,
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -  'mimeType': 'text/html',
[task 2023-09-08T10:22:11.070Z] 10:22:11     INFO -  'protocol': 'http/1.1',
[task 2023-09-08T10:22:11.071Z] 10:22:11     INFO -  'status': 200,
[task 2023-09-08T10:22:11.071Z] 10:22:11     INFO -  'statusText': 'OK',
[task 2023-09-08T10:22:11.071Z] 10:22:11     INFO -  'url': 'https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html'}
[task 2023-09-08T10:22:11.071Z] 10:22:11     INFO - html_url   = 'https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html'
[task 2023-09-08T10:22:11.071Z] 10:22:11     INFO - network_events = {'network.responseStarted': [{'context': '93ffdd66-53a5-470b-b24a-f1a607ef97f0',
[task 2023-09-08T10:22:11.071Z] 10:22:11     INFO -                               'isBlocked': False,
[task 2023-09-08T10:22:11.071Z] 10:22:11     INFO -                               'navigation': '6a7ade25-a97b-4e70-b524-2937d9cdf901',
[task 2023-09-08T10:22:11.071Z] 10:22:11     INFO -                               'redirectCount': 0,
[task 2023-09-08T10:22:11.071Z] 10:22:11     INFO -                               'request': {'bodySize': None,
[task 2023-09-08T10:22:11.071Z] 10:22:11     INFO -                                           'cookies': [],
[task 2023-09-08T10:22:11.071Z] 10:22:11     INFO -                                           'headers': [{'name': 'Host',
[task 2023-09-08T10:22:11.071Z] 10:22:11     INFO -                                                        'value': {'type': 'string',
[task 2023-09-08T10:22:11.071Z] 10:22:11     INFO -                                                                  'value': 'web-platform.test:8443'}},
[task 2023-09-08T10:22:11.071Z] 10:22:11     INFO -                                                       {'name': 'User-Agent',
[task 2023-09-08T10:22:11.071Z] 10:22:11     INFO -                                                        'value': {'type': 'string',
[task 2023-09-08T10:22:11.071Z] 10:22:11     INFO -                                                                  'value': 'Mozilla/5.0 '
[task 2023-09-08T10:22:11.071Z] 10:22:11     INFO -                                                                           '(X11; '
[task 2023-09-08T10:22:11.071Z] 10:22:11     INFO -                                                                           'Linux '
[task 2023-09-08T10:22:11.071Z] 10:22:11     INFO -                                                                           'x86_64; '
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                                           'rv:109.0) '
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                                           'Gecko/20100101 '
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                                           'Firefox/119.0'}},
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                       {'name': 'Accept',
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                        'value': {'type': 'string',
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                                  'value': 'text/html,application/xhtml+xml,application/xml;q=0.9,image/avif,image/webp,*/*;q=0.8'}},
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                       {'name': 'Accept-Language',
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                        'value': {'type': 'string',
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                                  'value': 'en-US,en;q=0.5'}},
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                       {'name': 'Accept-Encoding',
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                        'value': {'type': 'string',
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                                  'value': 'gzip, '
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                                           'deflate, '
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                                           'br'}},
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                       {'name': 'Connection',
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                        'value': {'type': 'string',
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                                  'value': 'keep-alive'}},
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                       {'name': 'Upgrade-Insecure-Requests',
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                        'value': {'type': 'string',
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                                  'value': '1'}},
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                       {'name': 'Sec-Fetch-Dest',
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                        'value': {'type': 'string',
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                                  'value': 'document'}},
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                       {'name': 'Sec-Fetch-Mode',
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                        'value': {'type': 'string',
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                                  'value': 'navigate'}},
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                       {'name': 'Sec-Fetch-Site',
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                        'value': {'type': 'string',
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                                  'value': 'none'}},
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                       {'name': 'Sec-Fetch-User',
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                        'value': {'type': 'string',
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                                  'value': '?1'}}],
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                           'headersSize': 491,
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                           'method': 'GET',
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                           'request': '15',
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                           'timings': {'connectEnd': 1694168521785625,
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                       'connectStart': 1694168521779958,
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                       'dnsEnd': 1694168521779424,
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                       'dnsStart': 1694168521779372,
[task 2023-09-08T10:22:11.072Z] 10:22:11     INFO -                                                       'fetchStart': 1694168521779372,
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                       'redirectEnd': 0,
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                       'redirectStart': 0,
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                       'requestStart': 1694168521785682,
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                       'requestTime': 1694168521774726,
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                       'responseEnd': 1694168521786984,
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                       'responseStart': 1694168521785700,
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                       'timeOrigin': 0,
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                       'tlsEnd': 1694168521785625,
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                       'tlsStart': 1694168521780231},
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                           'url': 'https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html'},
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                               'response': {'bodySize': 0,
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                            'bytesReceived': 116,
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                            'content': {'size': None},
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                            'fromCache': False,
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                            'headers': [{'name': 'Content-Type',
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                         'value': {'type': 'string',
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                                   'value': 'text/html'}},
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                        {'name': 'Server',
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                         'value': {'type': 'string',
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                                   'value': 'BaseHTTP/0.6 '
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                                            'Python/3.7.5'}},
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                        {'name': 'Date',
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                         'value': {'type': 'string',
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                                   'value': 'Fri, '
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                                            '08 '
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                                            'Sep '
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                                            '2023 '
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                                            '10:22:01 '
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                                                            'GMT'}}],
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                            'headersSize': 116,
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                            'mimeType': 'text/html',
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                            'protocol': 'http/1.1',
[task 2023-09-08T10:22:11.073Z] 10:22:11     INFO -                                            'status': 200,
[task 2023-09-08T10:22:11.074Z] 10:22:11     INFO -                                            'statusText': 'OK',
[task 2023-09-08T10:22:11.074Z] 10:22:11     INFO -                                            'url': 'https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html'},
[task 2023-09-08T10:22:11.074Z] 10:22:11     INFO -                               'timestamp': 1694168521787}]}
[task 2023-09-08T10:22:11.074Z] 10:22:11     INFO - on_response_started = <Future finished result={'context': '93ffdd66-53a...-f1a607ef97f0', 'isBlocked': False, 'navigation': '6a7ade25-a97...-2937d9cdf901', 'redirectCount': 0, ...}>
[task 2023-09-08T10:22:11.074Z] 10:22:11     INFO - result     = {'navigation': '39b74c6f-95d9-4bac-b07a-c4cf7e802508',
[task 2023-09-08T10:22:11.074Z] 10:22:11     INFO -  'url': 'https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html'}
[task 2023-09-08T10:22:11.074Z] 10:22:11     INFO - setup_network_test = <function setup_network_test.<locals>._setup_network_test at 0x7f284493b5f0>
[task 2023-09-08T10:22:11.074Z] 10:22:11     INFO - top_context = {'children': [],
[task 2023-09-08T10:22:11.074Z] 10:22:11     INFO -  'context': '93ffdd66-53a5-470b-b24a-f1a607ef97f0',
[task 2023-09-08T10:22:11.074Z] 10:22:11     INFO -  'parent': None,
[task 2023-09-08T10:22:11.074Z] 10:22:11     INFO -  'url': 'https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html'}
[task 2023-09-08T10:22:11.074Z] 10:22:11     INFO - url        = <function url.<locals>.url at 0x7f28449def80>
[task 2023-09-08T10:22:11.074Z] 10:22:11     INFO - wait_for_event = <function wait_for_event.<locals>.wait_for_event at 0x7f28449dee60>
[task 2023-09-08T10:22:11.074Z] 10:22:11     INFO - 
[task 2023-09-08T10:22:11.074Z] 10:22:11     INFO - tests/web-platform/tests/webdriver/tests/bidi/network/response_started/response_started.py:104: 
<...>
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO -               'url': 'https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html'},
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO -  'timestamp': 1694168521787}
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO -         expected_request = {'method': 'GET',
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO -  'url': 'https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html'}
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO -         expected_response = {'fromCache': False,
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO -  'mimeType': 'text/html',
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO -  'protocol': 'http/1.1',
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO -  'status': 200,
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO -  'statusText': 'OK',
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO -  'url': 'https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html'}
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO -         intercepts = None
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO -         is_blocked = None
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO -         navigation = '39b74c6f-95d9-4bac-b07a-c4cf7e802508'
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO -         redirect_count = 0
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO - 
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO - event = {'context': '93ffdd66-53a5-470b-b24a-f1a607ef97f0', 'isBlocked': False, 'navigation': '6a7ade25-a97b-4e70-b524-2937d9cdf901', 'redirectCount': 0, ...}
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO - context = None, intercepts = None, is_blocked = None
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO - navigation = '39b74c6f-95d9-4bac-b07a-c4cf7e802508', redirect_count = 0
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO - expected_request = {'method': 'GET', 'url': 'https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html'}
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO - 
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO -     def assert_base_parameters(
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO -         event,
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO -         context=None,
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO -         intercepts=None,
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO -         is_blocked=None,
[task 2023-09-08T10:22:11.077Z] 10:22:11     INFO -         navigation=None,
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -         redirect_count=None,
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -         expected_request=None,
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -     ):
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -         recursive_compare(
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -             {
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -                 "context": any_string_or_null,
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -                 "isBlocked": any_bool,
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -                 "navigation": any_string_or_null,
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -                 "redirectCount": any_int,
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -                 "request": any_dict,
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -                 "timestamp": any_int,
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -             },
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -             event,
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -         )
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -     
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -         if context is not None:
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -             assert event["context"] == context
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -     
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -         if is_blocked is not None:
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -             assert event["isBlocked"] == is_blocked
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -     
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -         if event["isBlocked"]:
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -             assert isinstance(event["intercepts"], list)
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -             assert len(event["intercepts"]) > 0
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -             for intercept in event["intercepts"]:
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -                 assert isinstance(intercept, str)
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -         else:
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -             assert "intercepts" not in event
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -     
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -         if intercepts is not None:
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -             assert event["intercepts"] == intercepts
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -     
[task 2023-09-08T10:22:11.078Z] 10:22:11     INFO -         if navigation is not None:
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO - >           assert event["navigation"] == navigation
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO - E           AssertionError
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO - 
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO - context    = None
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO - event      = {'context': '93ffdd66-53a5-470b-b24a-f1a607ef97f0',
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -  'isBlocked': False,
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -  'navigation': '6a7ade25-a97b-4e70-b524-2937d9cdf901',
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -  'redirectCount': 0,
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -  'request': {'bodySize': None,
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -              'cookies': [],
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -              'headers': [{'name': 'Host',
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -                           'value': {'type': 'string',
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -                                     'value': 'web-platform.test:8443'}},
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -                          {'name': 'User-Agent',
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -                           'value': {'type': 'string',
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -                                     'value': 'Mozilla/5.0 (X11; Linux x86_64; '
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -                                              'rv:109.0) Gecko/20100101 '
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -                                              'Firefox/119.0'}},
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -                          {'name': 'Accept',
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -                           'value': {'type': 'string',
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -                                     'value': 'text/html,application/xhtml+xml,application/xml;q=0.9,image/avif,image/webp,*/*;q=0.8'}},
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -                          {'name': 'Accept-Language',
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -                           'value': {'type': 'string',
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -                                     'value': 'en-US,en;q=0.5'}},
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -                          {'name': 'Accept-Encoding',
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -                           'value': {'type': 'string',
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -                                     'value': 'gzip, deflate, br'}},
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -                          {'name': 'Connection',
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -                           'value': {'type': 'string', 'value': 'keep-alive'}},
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -                          {'name': 'Upgrade-Insecure-Requests',
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -                           'value': {'type': 'string', 'value': '1'}},
[task 2023-09-08T10:22:11.079Z] 10:22:11     INFO -                          {'name': 'Sec-Fetch-Dest',
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                           'value': {'type': 'string', 'value': 'document'}},
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                          {'name': 'Sec-Fetch-Mode',
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                           'value': {'type': 'string', 'value': 'navigate'}},
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                          {'name': 'Sec-Fetch-Site',
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                           'value': {'type': 'string', 'value': 'none'}},
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                          {'name': 'Sec-Fetch-User',
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                           'value': {'type': 'string', 'value': '?1'}}],
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -              'headersSize': 491,
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -              'method': 'GET',
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -              'request': '15',
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -              'timings': {'connectEnd': 1694168521785625,
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                          'connectStart': 1694168521779958,
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                          'dnsEnd': 1694168521779424,
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                          'dnsStart': 1694168521779372,
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                          'fetchStart': 1694168521779372,
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                          'redirectEnd': 0,
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                          'redirectStart': 0,
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                          'requestStart': 1694168521785682,
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                          'requestTime': 1694168521774726,
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                          'responseEnd': 1694168521786984,
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                          'responseStart': 1694168521785700,
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                          'timeOrigin': 0,
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                          'tlsEnd': 1694168521785625,
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                          'tlsStart': 1694168521780231},
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -              'url': 'https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html'},
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -  'response': {'bodySize': 0,
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -               'bytesReceived': 116,
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -               'content': {'size': None},
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -               'fromCache': False,
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -               'headers': [{'name': 'Content-Type',
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                            'value': {'type': 'string', 'value': 'text/html'}},
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                           {'name': 'Server',
[task 2023-09-08T10:22:11.080Z] 10:22:11     INFO -                            'value': {'type': 'string',
[task 2023-09-08T10:22:11.081Z] 10:22:11     INFO -                                      'value': 'BaseHTTP/0.6 Python/3.7.5'}},
[task 2023-09-08T10:22:11.081Z] 10:22:11     INFO -                           {'name': 'Date',
[task 2023-09-08T10:22:11.081Z] 10:22:11     INFO -                            'value': {'type': 'string',
[task 2023-09-08T10:22:11.081Z] 10:22:11     INFO -                                      'value': 'Fri, 08 Sep 2023 10:22:01 '
[task 2023-09-08T10:22:11.081Z] 10:22:11     INFO -                                               'GMT'}}],
[task 2023-09-08T10:22:11.081Z] 10:22:11     INFO -               'headersSize': 116,
[task 2023-09-08T10:22:11.081Z] 10:22:11     INFO -               'mimeType': 'text/html',
[task 2023-09-08T10:22:11.081Z] 10:22:11     INFO -               'protocol': 'http/1.1',
[task 2023-09-08T10:22:11.081Z] 10:22:11     INFO -               'status': 200,
[task 2023-09-08T10:22:11.081Z] 10:22:11     INFO -               'statusText': 'OK',
[task 2023-09-08T10:22:11.081Z] 10:22:11     INFO -               'url': 'https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html'},
[task 2023-09-08T10:22:11.081Z] 10:22:11     INFO -  'timestamp': 1694168521787}
[task 2023-09-08T10:22:11.081Z] 10:22:11     INFO - expected_request = {'method': 'GET',
[task 2023-09-08T10:22:11.081Z] 10:22:11     INFO -  'url': 'https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html'}
[task 2023-09-08T10:22:11.081Z] 10:22:11     INFO - intercepts = None
[task 2023-09-08T10:22:11.081Z] 10:22:11     INFO - is_blocked = None
[task 2023-09-08T10:22:11.081Z] 10:22:11     INFO - navigation = '39b74c6f-95d9-4bac-b07a-c4cf7e802508'
[task 2023-09-08T10:22:11.081Z] 10:22:11     INFO - redirect_count = 0
[task 2023-09-08T10:22:11.081Z] 10:22:11     INFO - 
[task 2023-09-08T10:22:11.081Z] 10:22:11     INFO - tests/web-platform/tests/webdriver/tests/bidi/network/__init__.py:141: AssertionError
[task 2023-09-08T10:22:11.091Z] 10:22:11     INFO - ..........................................
[task 2023-09-08T10:22:11.091Z] 10:22:11     INFO - TEST-OK | /webdriver/tests/bidi/network/response_started/response_started.py | took 11742ms
Summary: Intermittent [tier 2] /webdriver/tests/bidi/network/response_started/response_started.py | test_load_page_twice - AssertionError (assert event["navigation"] == navigation → Intermittent /webdriver/tests/bidi/network/response_started/response_started.py | test_load_page_twice - AssertionError (assert event["navigation"] == navigation

Done some retriggers and backfills and reached down to here.

Hello Henrik! Could you please take a look at this bug? It looks like it started once Bug 1850166 landed on Autoland.

Thank you!

Flags: needinfo?(hskupin)

(In reply to Serban Stanca [:SerbanS] from comment #2)

Done some retriggers and backfills and reached down to here.

Hello Henrik! Could you please take a look at this bug? It looks like it started once Bug 1850166 landed on Autoland.

This is very unlikely. I really think that bug 1848156 might have caused this regression. Julian, mind having a look at it?

Flags: needinfo?(hskupin) → needinfo?(jdescottes)

Thanks for the ping, yes it makes sense that it relates to Bug 1848156.

Basically after that bug, we bail out quite early from processing network events if we are not subscribed to them.
But we know that our navigation listener will sometimes catch navigations only after the early network events have been received, which is why we allow beforeRequestSent to create a new navigation if needed. But this test only listens to responseStarted and therefore we skip creating the navigation in beforeRequestSent.

I didn't really think responseStarted could be received before the navigation listener gets notified, but it seems to be the case. In particular here, the navigation listener child actor does receive the onStateChange before we process the responseStarted event, but we only receive the update in the parent process AFTER we processed the network event.

I see 2 options:

  • either ensure we process the navigation information before bailing out from the beforeRequestSent event
  • or allow all network events to create a navigation if needed

On a more general note, we could generate the navigation id in browsingContext.navigate/reload (which would match the spec and my comment over at https://github.com/w3c/webdriver-bidi/issues/527#issuecomment-1706959563). But that's a slightly different topic.

I'll check if both my suggestions work and we can decide which one makes the most sense.

Flags: needinfo?(jdescottes)
Assignee: nobody → jdescottes
Status: NEW → ASSIGNED
Points: --- → 1
Whiteboard: [webdriver:m8]
Pushed by jdescottes@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/c37f69352a01
[bidi] Always process navigation data when handling network events r=webdriver-reviewers,whimboo
Status: ASSIGNED → RESOLVED
Closed: 8 months ago
Resolution: --- → FIXED
Target Milestone: --- → 119 Branch
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: