1767839 - Intermittent [tier 2] /webdriver/tests/bidi/browsing_context/navigate/wait.py | test_slow_script[<random>-True] - AssertionError: assert 'about:blank' == 'http://web-platform.test:8000/webdriver/tests/support/inline.py?doc=%3C%21doctype+html%3E%0A%3Cmeta+

Status: RESOLVED DUPLICATE of bug 1785544

(Remote Protocol :: Marionette, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2022-05-04T17:05:20.040Z] 17:05:20     INFO - TEST-PASS | /webdriver/tests/bidi/browsing_context/navigate/wait.py | test_slow_script[interactive-True] 
[task 2022-05-04T17:05:20.040Z] 17:05:20     INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/bidi/browsing_context/navigate/wait.py | test_slow_script[complete-True] - AssertionError: assert 'about:blank' == 'http://web-platform.test:8000/webdriver/tests/support/inline.py?doc=%3C%21doctype+html%3E%0A%3Cmeta+charset%3DUTF-8%3E%0A%3Cscript+src%3D%27%2Fwebdriver%2Ftests%2Fbidi%2Fbrowsing_context%2Fnavigate%2Fsupport%2Fempty.js%3Fpipe%3Dtrickle%28d10%29%27%3E%3C%2Fscript%3E&mime=text%2Fhtml&charset=UTF-8'
[task 2022-05-04T17:05:20.040Z] 17:05:20     INFO - bidi_session = <webdriver.bidi.client.BidiSession object at 0x7fe34019b978>
[task 2022-05-04T17:05:20.040Z] 17:05:20     INFO - inline = <function inline.<locals>.inline at 0x7fe3400ff510>
[task 2022-05-04T17:05:20.040Z] 17:05:20     INFO - new_tab = {'children': None, 'context': '00115645-105d-483e-95f2-cc0217e0f30b', 'parent': None, 'url': 'about:blank'}
[task 2022-05-04T17:05:20.040Z] 17:05:20     INFO - wait = 'complete', expect_timeout = True
[task 2022-05-04T17:05:20.040Z] 17:05:20     INFO - 
[task 2022-05-04T17:05:20.040Z] 17:05:20     INFO -     @pytest.mark.parametrize(
[task 2022-05-04T17:05:20.040Z] 17:05:20     INFO -         "wait, expect_timeout",
[task 2022-05-04T17:05:20.040Z] 17:05:20     INFO -         [
[task 2022-05-04T17:05:20.040Z] 17:05:20     INFO -             ("none", False),
[task 2022-05-04T17:05:20.041Z] 17:05:20     INFO -             ("interactive", True),
[task 2022-05-04T17:05:20.041Z] 17:05:20     INFO -             ("complete", True),
[task 2022-05-04T17:05:20.041Z] 17:05:20     INFO -         ],
[task 2022-05-04T17:05:20.041Z] 17:05:20     INFO -     )
[task 2022-05-04T17:05:20.041Z] 17:05:20     INFO -     async def test_slow_script(bidi_session, inline, new_tab, wait, expect_timeout):
[task 2022-05-04T17:05:20.041Z] 17:05:20     INFO -         script_url = "/webdriver/tests/bidi/browsing_context/navigate/support/empty.js"
[task 2022-05-04T17:05:20.041Z] 17:05:20     INFO -         url = inline(f"<script src='{script_url}?pipe=trickle(d10)'></script>")
[task 2022-05-04T17:05:20.041Z] 17:05:20     INFO -     
[task 2022-05-04T17:05:20.041Z] 17:05:20     INFO -         wait_for_navigation = asyncio.wait_for(
[task 2022-05-04T17:05:20.041Z] 17:05:20     INFO -             bidi_session.browsing_context.navigate(
[task 2022-05-04T17:05:20.041Z] 17:05:20     INFO -                 context=new_tab["context"], url=url, wait=wait
[task 2022-05-04T17:05:20.041Z] 17:05:20     INFO -             ),
[task 2022-05-04T17:05:20.041Z] 17:05:20     INFO -             timeout=1,
[task 2022-05-04T17:05:20.041Z] 17:05:20     INFO -         )
[task 2022-05-04T17:05:20.042Z] 17:05:20     INFO -     
[task 2022-05-04T17:05:20.042Z] 17:05:20     INFO -         if expect_timeout:
[task 2022-05-04T17:05:20.042Z] 17:05:20     INFO -             with pytest.raises(asyncio.TimeoutError):
[task 2022-05-04T17:05:20.042Z] 17:05:20     INFO -                 await wait_for_navigation
[task 2022-05-04T17:05:20.042Z] 17:05:20     INFO -         else:
[task 2022-05-04T17:05:20.042Z] 17:05:20     INFO -             await wait_for_navigation
[task 2022-05-04T17:05:20.042Z] 17:05:20     INFO -     
[task 2022-05-04T17:05:20.042Z] 17:05:20     INFO -         if wait != "none":
[task 2022-05-04T17:05:20.042Z] 17:05:20     INFO -             contexts = await bidi_session.browsing_context.get_tree(
[task 2022-05-04T17:05:20.042Z] 17:05:20     INFO -                 root=new_tab["context"], max_depth=0
[task 2022-05-04T17:05:20.042Z] 17:05:20     INFO -             )
[task 2022-05-04T17:05:20.042Z] 17:05:20     INFO - >           assert contexts[0]["url"] == url
[task 2022-05-04T17:05:20.042Z] 17:05:20     INFO - E           AssertionError: assert 'about:blank' == 'http://web-platform.test:8000/webdriver/tests/support/inline.py?doc=%3C%21doctype+html%3E%0A%3Cmeta+charset%3DUTF-8%3E%0A%3Cscript+src%3D%27%2Fwebdriver%2Ftests%2Fbidi%2Fbrowsing_context%2Fnavigate%2Fsupport%2Fempty.js%3Fpipe%3Dtrickle%28d10%29%27%3E%3C%2Fscript%3E&mime=text%2Fhtml&charset=UTF-8'
[task 2022-05-04T17:05:20.042Z] 17:05:20     INFO - E             - http://web-platform.test:8000/webdriver/tests/support/inline.py?doc=%3C%21doctype+html%3E%0A%3Cmeta+charset%3DUTF-8%3E%0A%3Cscript+src%3D%27%2Fwebdriver%2Ftests%2Fbidi%2Fbrowsing_context%2Fnavigate%2Fsupport%2Fempty.js%3Fpipe%3Dtrickle%28d10%29%27%3E%3C%2Fscript%3E&mime=text%2Fhtml&charset=UTF-8
[task 2022-05-04T17:05:20.043Z] 17:05:20     INFO - E             + about:blank
[task 2022-05-04T17:05:20.043Z] 17:05:20     INFO - 
[task 2022-05-04T17:05:20.043Z] 17:05:20     INFO - bidi_session = <webdriver.bidi.client.BidiSession object at 0x7fe34019b978>
[task 2022-05-04T17:05:20.043Z] 17:05:20     INFO - contexts   = [{'children': None,
[task 2022-05-04T17:05:20.043Z] 17:05:20     INFO -   'context': '00115645-105d-483e-95f2-cc0217e0f30b',
[task 2022-05-04T17:05:20.043Z] 17:05:20     INFO -   'parent': None,
[task 2022-05-04T17:05:20.043Z] 17:05:20     INFO -   'url': 'about:blank'}]
[task 2022-05-04T17:05:20.043Z] 17:05:20     INFO - expect_timeout = True[task 2022-05-04T17:05:20.043Z] 17:05:20     INFO - inline     = <function inline.<locals>.inline at 0x7fe3400ff510>
[task 2022-05-04T17:05:20.043Z] 17:05:20     INFO - new_tab    = {'children': None,
[task 2022-05-04T17:05:20.043Z] 17:05:20     INFO -  'context': '00115645-105d-483e-95f2-cc0217e0f30b',
[task 2022-05-04T17:05:20.043Z] 17:05:20     INFO -  'parent': None,
[task 2022-05-04T17:05:20.044Z] 17:05:20     INFO -  'url': 'about:blank'}
[task 2022-05-04T17:05:20.044Z] 17:05:20     INFO - script_url = '/webdriver/tests/bidi/browsing_context/navigate/support/empty.js'
[task 2022-05-04T17:05:20.044Z] 17:05:20     INFO - url        = 'http://web-platform.test:8000/webdriver/tests/support/inline.py?doc=%3C%21doctype+html%3E%0A%3Cmeta+charset%3DUTF-8%3E%0A%3Cscript+src%3D%27%2Fwebdriver%2Ftests%2Fbidi%2Fbrowsing_context%2Fnavigate%2Fsupport%2Fempty.js%3Fpipe%3Dtrickle%28d10%29%27%3E%3C%2Fscript%3E&mime=text%2Fhtml&charset=UTF-8'
[task 2022-05-04T17:05:20.044Z] 17:05:20     INFO - wait       = 'complete'
[task 2022-05-04T17:05:20.044Z] 17:05:20     INFO - wait_for_navigation = <generator object wait_for at 0x7fe34010f258>
[task 2022-05-04T17:05:20.044Z] 17:05:20     INFO - 
[task 2022-05-04T17:05:20.044Z] 17:05:20     INFO - tests/web-platform/tests/webdriver/tests/bidi/browsing_context/navigate/wait.py:114: AssertionError
[task 2022-05-04T17:05:20.044Z] 17:05:20     INFO - TEST-OK | /webdriver/tests/bidi/browsing_context/navigate/wait.py | took 15360ms

Comment 1

[Henrik Skupin [:whimboo][⌚️UTC+2]]

This is most likely a race given that we wait for 1s only in the test:

https://treeherder.mozilla.org/logviewer?job_id=376835471&repo=mozilla-central&lineNumber=10925-10935

1651683916247	WebDriver BiDi	DEBUG	05992f7f-1000-427e-b4af-4c1378fb8fd2 -> {"id":40,"method":"browsingContext.navigate","params":{"context":"00115645-105d-483e-95f2-cc0217e0f30b","url":"http://web-pla ... igate%2Fsupport%2Fempty.js%3Fpipe%3Dtrickle%28d10%29%27%3E%3C%2Fscript%3E&mime=text%2Fhtml&charset=UTF-8","wait":"complete"}}
1651683916249	RemoteAgent	TRACE	Received command browsingContext.navigate for destination ROOT
1651683916250	RemoteAgent	TRACE	Received command browsingContext._getBaseURL for destination WINDOW_GLOBAL
1651683916252	RemoteAgent	TRACE	Received command browsingContext._getBaseURL for destination WINDOW_GLOBAL
1651683916252	RemoteAgent	TRACE	Module windowglobal/browsingContext.jsm found for WINDOW_GLOBAL
1651683916275	RemoteAgent	TRACE	[77] ProgressListener state=start: http://web-platform.test:8000/webdriver/tests/support/inline.py?doc=%3C%21doctype+html%3E%0A%3Cmeta+charset%3DUTF-8%3E%0A%3Cs ... rowsing_context%2Fnavigate%2Fsupport%2Fempty.js%3Fpipe%3Dtrickle%28d10%29%27%3E%3C%2Fscript%3E&mime=text%2Fhtml&charset=UTF-8
1651683916331	Marionette	TRACE	Remoteness change detected. Set new top-level browsing context to 79
[CodeCoverage] Setting handlers for process 4396.
1651683917248	WebDriver BiDi	DEBUG	05992f7f-1000-427e-b4af-4c1378fb8fd2 -> {"id":41,"method":"browsingContext.getTree","params":{"maxDepth":0,"root":"00115645-105d-483e-95f2-cc0217e0f30b"}}
1651683917249	RemoteAgent	TRACE	Received command browsingContext.getTree for destination ROOT
1651683917249	WebDriver BiDi	DEBUG	05992f7f-1000-427e-b4af-4c1378fb8fd2 <- {"id":41,"result":{"contexts":[{"context":"00115645-105d-483e-95f2-cc0217e0f30b","url":"about:blank","children":null,"parent":null}]}}

Looks like this failure depends on bug 1767295.

Comment 2

[Intermittent Failures Robot]

1 failures in 3540 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-ccov-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1767839&startday=2022-05-02&endday=2022-05-08&tree=all

Comment 4

[Intermittent Failures Robot]

1 failures in 3317 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• macosx1015-64-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1767839&startday=2022-05-09&endday=2022-05-15&tree=all

Comment 5

[Intermittent Failures Robot]

2 failures in 2694 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1
• try: 1

Platform and build breakdown:

• linux1804-64-ccov-qr: 1
  • opt: 1
• windows10-64-2004-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1767839&startday=2022-05-16&endday=2022-05-22&tree=all

Comment 6

[Intermittent Failures Robot]

1 failures in 2624 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-ccov-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1767839&startday=2022-05-23&endday=2022-05-29&tree=all

Comment 7

[Intermittent Failures Robot]

3 failures in 2917 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 1
• mozilla-central: 2

Platform and build breakdown:

• macosx1015-64-shippable-qr: 1
  • opt: 1
• linux1804-64-ccov-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1767839&startday=2022-05-30&endday=2022-06-05&tree=all

Comment 8

[Intermittent Failures Robot]

1 failures in 3336 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-ccov-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1767839&startday=2022-06-06&endday=2022-06-12&tree=all

Comment 9

[Intermittent Failures Robot]

2 failures in 3170 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1
• mozilla-beta: 1

Platform and build breakdown:

• linux1804-64-ccov-qr: 1
  • opt: 1
• macosx1015-64-devedition-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1767839&startday=2022-06-13&endday=2022-06-19&tree=all

Comment 11

[Intermittent Failures Robot]

2 failures in 3312 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 2

Platform and build breakdown:

• linux1804-64-ccov-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1767839&startday=2022-06-27&endday=2022-07-03&tree=all

Comment 12

[Intermittent Failures Robot]

1 failures in 3072 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1767839&startday=2022-07-04&endday=2022-07-10&tree=all

Comment 13

[Henrik Skupin [:whimboo][⌚️UTC+2]]

Might be similar to bug 1764661.

Comment 14

[Intermittent Failures Robot]

2 failures in 3341 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1
• autoland: 1

Platform and build breakdown:

• linux1804-64-ccov-qr: 1
  • opt: 1
• windows10-64-2004-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1767839&startday=2022-07-11&endday=2022-07-17&tree=all

Comment 15

[Intermittent Failures Robot]

1 failures in 3497 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-ccov-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1767839&startday=2022-07-18&endday=2022-07-24&tree=all

Comment 16

[Intermittent Failures Robot]

1 failures in 3245 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-ccov-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1767839&startday=2022-07-25&endday=2022-07-31&tree=all

Comment 18

[BMO Automation]

Moving bug to Remote Protocol::Marionette component per bug 1815831.