1776807 - Intermittent mozilla/tests/webdriver/cdp/port_file.py | <random> - assert 0 == 2

Status: RESOLVED INCOMPLETE

(Remote Protocol :: Marionette, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2022-06-28T02:35:06.561Z] 02:35:06     INFO - TEST-START | /_mozilla/webdriver/cdp/port_file.py
[task 2022-06-28T02:35:06.621Z] 02:35:06     INFO - STDOUT: ============================= test session starts ==============================
[task 2022-06-28T02:35:06.624Z] 02:35:06     INFO - STDOUT: platform linux -- Python 3.6.9, pytest-7.0.1, pluggy-unknown -- /builds/worker/workspace/build/venv/bin/python
[task 2022-06-28T02:35:06.624Z] 02:35:06     INFO - STDOUT: rootdir: /builds/worker/workspace/build
[task 2022-06-28T02:35:06.624Z] 02:35:06     INFO - STDOUT: plugins: asyncio-0.14.0
[task 2022-06-28T02:35:06.624Z] 02:35:06     INFO - STDOUT: collecting ... 
[task 2022-06-28T02:35:06.626Z] 02:35:06     INFO - STDOUT: collected 2 items
[task 2022-06-28T02:35:06.627Z] 02:35:06     INFO - STDOUT: tests/web-platform/mozilla/tests/webdriver/cdp/port_file.py::test_devtools_active_port_file 
[task 2022-06-28T02:35:06.863Z] 02:35:06     INFO - STDOUT: [Parent 5060, Main Thread] WARNING: Rejected attempt to change type of pref extensions.formautofill.creditCards.available's user value from bool to string: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:1748
[task 2022-06-28T02:35:06.973Z] 02:35:06     INFO - STDOUT: [Parent 5060, Main Thread] WARNING: GLX_swap_control unsupported, ASAP mode may still block on buffer swaps.: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderGLX.cpp:214
[task 2022-06-28T02:35:08.351Z] 02:35:08     INFO - STDOUT: 1656383708349	RemoteAgent	DEBUG	WebDriver BiDi enabled
[task 2022-06-28T02:35:08.351Z] 02:35:08     INFO - STDOUT: 1656383708349	RemoteAgent	DEBUG	CDP enabled
[task 2022-06-28T02:35:08.353Z] 02:35:08     INFO - STDOUT: 1656383708350	RemoteAgent	TRACE	Received observer notification final-ui-startup
[task 2022-06-28T02:35:08.357Z] 02:35:08     INFO - STDOUT: WebDriver BiDi listening on ws://localhost:38928
[task 2022-06-28T02:35:08.415Z] 02:35:08     INFO - STDOUT: 1656383708414	CDP	DEBUG	Waiting for initial application window
[task 2022-06-28T02:35:08.424Z] 02:35:08     INFO - STDOUT: [GLX] window c00003 has VisualID 0x41
[task 2022-06-28T02:35:08.433Z] 02:35:08     INFO - STDOUT: GL_VENDOR: VMware, Inc.
[task 2022-06-28T02:35:08.435Z] 02:35:08     INFO - STDOUT: mVendor: VMware, Inc.
[task 2022-06-28T02:35:08.436Z] 02:35:08     INFO - STDOUT: GL_RENDERER: llvmpipe (LLVM 10.0.0, 256 bits)
[task 2022-06-28T02:35:08.437Z] 02:35:08     INFO - STDOUT: mRenderer: Unknown
[task 2022-06-28T02:35:08.438Z] 02:35:08     INFO - STDOUT: mIsMesa: 1
[task 2022-06-28T02:35:08.438Z] 02:35:08     INFO - STDOUT: [Parent 5060, Renderer] WARNING: robust_buffer_access_behavior marked as unsupported: file /builds/worker/checkouts/gecko/gfx/gl/GLContextFeatures.cpp:632
[task 2022-06-28T02:35:08.440Z] 02:35:08     INFO - STDOUT: [Parent 5060, Renderer] WARNING: Robustness supported, strategy is not LOSE_CONTEXT_ON_RESET!: file /builds/worker/checkouts/gecko/gfx/gl/GLContext.cpp:999
[task 2022-06-28T02:35:08.440Z] 02:35:08     INFO - STDOUT: [Parent 5060, Renderer] WARNING: robustness marked as unsupported: file /builds/worker/checkouts/gecko/gfx/gl/GLContextFeatures.cpp:632
[task 2022-06-28T02:35:08.440Z] 02:35:08     INFO - STDOUT: [2022-06-28T02:35:08Z WARN  webrender::device::gl] Missing optimized shader source for gpu_cache_update
<...>
[task 2022-06-28T02:35:11.322Z] 02:35:11     INFO - STDOUT: DevTools listening on ws://localhost:38928/devtools/browser/54431b76-6bfd-4347-8b62-78ac6cc6eb0e
[task 2022-06-28T02:35:11.434Z] 02:35:11     INFO - STDOUT: FAILED
[task 2022-06-28T02:35:11.440Z] 02:35:11     INFO - STDOUT: tests/web-platform/mozilla/tests/webdriver/cdp/port_file.py::test_connect 
[task 2022-06-28T02:35:11.482Z] 02:35:11     INFO - STDOUT: FAILED
[task 2022-06-28T02:35:11.560Z] 02:35:11     INFO - STDOUT: =================================== FAILURES ===================================
[task 2022-06-28T02:35:11.562Z] 02:35:11     INFO - STDOUT: ________________________ test_devtools_active_port_file ________________________
[task 2022-06-28T02:35:11.563Z] 02:35:11     INFO - STDOUT: browser = <function browser.<locals>._browser at 0x7f6a60313e18>
[task 2022-06-28T02:35:11.563Z] 02:35:11     INFO - STDOUT:     def test_devtools_active_port_file(browser):
[task 2022-06-28T02:35:11.566Z] 02:35:11     INFO - STDOUT: >       current_browser = browser(use_cdp=True)
[task 2022-06-28T02:35:11.566Z] 02:35:11     INFO - STDOUT: browser    = <function browser.<locals>._browser at 0x7f6a60313e18>
[task 2022-06-28T02:35:11.566Z] 02:35:11     INFO - STDOUT: tests/web-platform/mozilla/tests/webdriver/cdp/port_file.py
[task 2022-06-28T02:35:11.566Z] 02:35:11     INFO - STDOUT: :7: 
[task 2022-06-28T02:35:11.575Z] 02:35:11     INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2022-06-28T02:35:11.575Z] 02:35:11     INFO - STDOUT: tests/web-platform/mozilla/tests/webdriver/support/fixtures.py
[task 2022-06-28T02:35:11.575Z] 02:35:11     INFO - STDOUT: :57: in _browser
[task 2022-06-28T02:35:11.575Z] 02:35:11     INFO - STDOUT:     current_browser.start()
[task 2022-06-28T02:35:11.576Z] 02:35:11     INFO - STDOUT:         current_browser = <support.fixtures.Browser object at 0x7f6a600dc198>
[task 2022-06-28T02:35:11.576Z] 02:35:11     INFO - STDOUT:         extra_args = None
[task 2022-06-28T02:35:11.576Z] 02:35:11     INFO - STDOUT:         extra_prefs = None
[task 2022-06-28T02:35:11.576Z] 02:35:11     INFO - STDOUT:         firefox_options = {'args': ['--profile', '/tmp/tmpqqxs1lnf.mozrunner'],
[task 2022-06-28T02:35:11.576Z] 02:35:11     INFO -  'binary': '/builds/worker/workspace/build/application/firefox/firefox'}
[task 2022-06-28T02:35:11.630Z] 02:35:11     INFO - 
[task 2022-06-28T02:35:11.630Z] 02:35:11     INFO - TEST-UNEXPECTED-FAIL | /_mozilla/webdriver/cdp/port_file.py | test_devtools_active_port_file - assert 0 == 2
[task 2022-06-28T02:35:11.630Z] 02:35:11     INFO - browser = <function browser.<locals>._browser at 0x7f6a60313e18>
[task 2022-06-28T02:35:11.630Z] 02:35:11     INFO - 
[task 2022-06-28T02:35:11.630Z] 02:35:11     INFO -     def test_devtools_active_port_file(browser):
[task 2022-06-28T02:35:11.630Z] 02:35:11     INFO - >       current_browser = browser(use_cdp=True)
[task 2022-06-28T02:35:11.630Z] 02:35:11     INFO - 
[task 2022-06-28T02:35:11.630Z] 02:35:11     INFO - browser    = <function browser.<locals>._browser at 0x7f6a60313e18>
[task 2022-06-28T02:35:11.631Z] 02:35:11     INFO - 
[task 2022-06-28T02:35:11.631Z] 02:35:11     INFO - tests/web-platform/mozilla/tests/webdriver/cdp/port_file.py:7: 
[task 2022-06-28T02:35:11.631Z] 02:35:11     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2022-06-28T02:35:11.631Z] 02:35:11     INFO - tests/web-platform/mozilla/tests/webdriver/support/fixtures.py:57: in _browser
[task 2022-06-28T02:35:11.631Z] 02:35:11     INFO -     current_browser.start()
[task 2022-06-28T02:35:11.632Z] 02:35:11     INFO -         current_browser = <support.fixtures.Browser object at 0x7f6a600dc198>
[task 2022-06-28T02:35:11.632Z] 02:35:11     INFO -         extra_args = None
[task 2022-06-28T02:35:11.632Z] 02:35:11     INFO -         extra_prefs = None
[task 2022-06-28T02:35:11.632Z] 02:35:11     INFO -         firefox_options = {'args': ['--profile', '/tmp/tmpqqxs1lnf.mozrunner'],
[task 2022-06-28T02:35:11.632Z] 02:35:11     INFO -  'binary': '/builds/worker/workspace/build/application/firefox/firefox'}
[task 2022-06-28T02:35:11.632Z] 02:35:11     INFO -         full_configuration = {'capabilities': {'moz:firefoxOptions': {'args': ['--profile',
[task 2022-06-28T02:35:11.632Z] 02:35:11     INFO -                                                   '/tmp/tmpqqxs1lnf.mozrunner'],
[task 2022-06-28T02:35:11.632Z] 02:35:11     INFO -                                          'binary': '/builds/worker/workspace/build/application/firefox/firefox'}},
[task 2022-06-28T02:35:11.633Z] 02:35:11     INFO -  'host': '127.0.0.1',
[task 2022-06-28T02:35:11.633Z] 02:35:11     INFO -  'port': 39818,
[task 2022-06-28T02:35:11.633Z] 02:35:11     INFO -  'webdriver': {'args': ['-vv'], 'binary': '/builds/worker/fetches/geckodriver'},
[task 2022-06-28T02:35:11.633Z] 02:35:11     INFO -  'wptserve': {'aliases': [],
[task 2022-06-28T02:35:11.633Z] 02:35:11     INFO -               'all_domains': {'': {'': 'web-platform.test',
[task 2022-06-28T02:35:11.633Z] 02:35:11     INFO -                                    'nonexistent': 'nonexistent.web-platform.test',
[task 2022-06-28T02:35:11.634Z] 02:35:11     INFO -                                    'nonexistent.nonexistent': 'nonexistent.nonexistent.web-platform.test',
[task 2022-06-28T02:35:11.634Z] 02:35:11     INFO -                                    'op1': 'op1.web-platform.test',
<...>
[task 2022-06-28T02:35:11.789Z] 02:35:11     INFO -                              'op6'],
[task 2022-06-28T02:35:11.789Z] 02:35:11     INFO -               'ws_doc_root': '/builds/worker/workspace/build/tests/web-platform/tests/websockets/handlers'}}
[task 2022-06-28T02:35:11.789Z] 02:35:11     INFO -         use_bidi   = False
[task 2022-06-28T02:35:11.790Z] 02:35:11     INFO -         use_cdp    = True
[task 2022-06-28T02:35:11.790Z] 02:35:11     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2022-06-28T02:35:11.790Z] 02:35:11     INFO - 
[task 2022-06-28T02:35:11.790Z] 02:35:11     INFO - self = <support.fixtures.Browser object at 0x7f6a600dc198>
[task 2022-06-28T02:35:11.790Z] 02:35:11     INFO - 
[task 2022-06-28T02:35:11.790Z] 02:35:11     INFO -     def start(self):
[task 2022-06-28T02:35:11.790Z] 02:35:11     INFO -         # Start Firefox.
[task 2022-06-28T02:35:11.791Z] 02:35:11     INFO -         self.runner.start()
[task 2022-06-28T02:35:11.791Z] 02:35:11     INFO -     
[task 2022-06-28T02:35:11.791Z] 02:35:11     INFO -         if self.use_bidi:
[task 2022-06-28T02:35:11.791Z] 02:35:11     INFO -             # Wait until the WebDriverBiDiActivePort file is ready
[task 2022-06-28T02:35:11.791Z] 02:35:11     INFO -             while not os.path.exists(self.bidi_port_file):
[task 2022-06-28T02:35:11.791Z] 02:35:11     INFO -                 time.sleep(0.1)
[task 2022-06-28T02:35:11.791Z] 02:35:11     INFO -     
[task 2022-06-28T02:35:11.792Z] 02:35:11     INFO -             # Read the port from the WebDriverBiDiActivePort file
[task 2022-06-28T02:35:11.792Z] 02:35:11     INFO -             self.remote_agent_port = int(open(self.bidi_port_file).read())
[task 2022-06-28T02:35:11.792Z] 02:35:11     INFO -     
[task 2022-06-28T02:35:11.792Z] 02:35:11     INFO -         if self.use_cdp:
[task 2022-06-28T02:35:11.792Z] 02:35:11     INFO -             # Wait until the DevToolsActivePort file is ready
[task 2022-06-28T02:35:11.792Z] 02:35:11     INFO -             while not os.path.exists(self.cdp_port_file):
[task 2022-06-28T02:35:11.792Z] 02:35:11     INFO -                 time.sleep(0.1)
[task 2022-06-28T02:35:11.792Z] 02:35:11     INFO -     
[task 2022-06-28T02:35:11.793Z] 02:35:11     INFO -             # Read the port if needed and the debugger address from the
[task 2022-06-28T02:35:11.793Z] 02:35:11     INFO -             # DevToolsActivePort file
[task 2022-06-28T02:35:11.793Z] 02:35:11     INFO -             lines = open(self.cdp_port_file).readlines()
[task 2022-06-28T02:35:11.793Z] 02:35:11     INFO - >           assert len(lines) == 2
[task 2022-06-28T02:35:11.793Z] 02:35:11     INFO - E           assert 0 == 2
[task 2022-06-28T02:35:11.793Z] 02:35:11     INFO - E             +0
[task 2022-06-28T02:35:11.793Z] 02:35:11     INFO - E             -2
[task 2022-06-28T02:35:11.793Z] 02:35:11     INFO - 
[task 2022-06-28T02:35:11.793Z] 02:35:11     INFO - lines      = []
[task 2022-06-28T02:35:11.794Z] 02:35:11     INFO - self       = <support.fixtures.Browser object at 0x7f6a600dc198>
[task 2022-06-28T02:35:11.794Z] 02:35:11     INFO - 
[task 2022-06-28T02:35:11.794Z] 02:35:11     INFO - tests/web-platform/mozilla/tests/webdriver/support/fixtures.py:176: AssertionError
[task 2022-06-28T02:35:11.794Z] 02:35:11     INFO - 
[task 2022-06-28T02:35:11.794Z] 02:35:11     INFO - TEST-UNEXPECTED-FAIL | /_mozilla/webdriver/cdp/port_file.py | test_connect - ValueError: invalid literal for int() with base 10: 'None'
[task 2022-06-28T02:35:11.794Z] 02:35:11     INFO - self = <http.client.HTTPConnection object at 0x7f6a6032aeb8>
[task 2022-06-28T02:35:11.794Z] 02:35:11     INFO - host = 'localhost:None', port = None
[task 2022-06-28T02:35:11.794Z] 02:35:11     INFO - 
[task 2022-06-28T02:35:11.794Z] 02:35:11     INFO -     def _get_hostport(self, host, port):
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -         if port is None:
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -             i = host.rfind(':')
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -             j = host.rfind(']')         # ipv6 addresses have [...]
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -             if i > j:
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -                 try:
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - >                   port = int(host[i+1:])
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - E                   ValueError: invalid literal for int() with base 10: 'None'
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - 
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - host       = 'localhost:None'
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - i          = 9
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - j          = -1
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - port       = None
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - self       = <http.client.HTTPConnection object at 0x7f6a6032aeb8>
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - 
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - /usr/lib/python3.6/http/client.py:905: ValueError
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - 
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - During handling of the above exception, another exception occurred:
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - 
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - browser = <function browser.<locals>._browser at 0x7f6a60313e18>
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - 
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -     def test_connect(browser):
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -         current_browser = browser(use_cdp=True)
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -     
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -         response = websocket_request(
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - >           current_browser.remote_agent_port, path=current_browser.debugger_address
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -         )
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - 
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - browser    = <function browser.<locals>._browser at 0x7f6a60313e18>
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - current_browser = <support.fixtures.Browser object at 0x7f6a600dc198>
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - 
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - tests/web-platform/mozilla/tests/webdriver/cdp/port_file.py:23: 
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - tests/web-platform/mozilla/tests/webdriver/support/network.py:9: in websocket_request
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -     conn = HTTPConnection(real_host)
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -         host       = None
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -         origin     = None
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -         path       = None
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -         real_host  = 'localhost:None'
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -         remote_agent_port = None
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -         url        = 'http://localhost:NoneNone'
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - /usr/lib/python3.6/http/client.py:867: in __init__
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -     (self.host, self.port) = self._get_hostport(host, port)
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -         host       = 'localhost:None'
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -         port       = None
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -         self       = <http.client.HTTPConnection object at 0x7f6a6032aeb8>
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -         source_address = None
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -         timeout    = <object object at 0x7f6a677da160>
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - 
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - self = <http.client.HTTPConnection object at 0x7f6a6032aeb8>
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - host = 'localhost:None', port = None
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - 
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -     def _get_hostport(self, host, port):
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -         if port is None:
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -             i = host.rfind(':')
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -             j = host.rfind(']')         # ipv6 addresses have [...]
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -             if i > j:
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -                 try:
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -                     port = int(host[i+1:])
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -                 except ValueError:
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -                     if host[i+1:] == "": # http://foo.com:/ == http://foo.com/
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -                         port = self.default_port
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO -                     else:
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - >                       raise InvalidURL("nonnumeric port: '%s'" % host[i+1:])
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - E                       http.client.InvalidURL: nonnumeric port: 'None'
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - 
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - host       = 'localhost:None'
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - i          = 9
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - j          = -1
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - port       = None
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - self       = <http.client.HTTPConnection object at 0x7f6a6032aeb8>
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - 
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - /usr/lib/python3.6/http/client.py:910: InvalidURL
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - TEST-OK | /_mozilla/webdriver/cdp/port_file.py | took 5030ms
[task 2022-06-28T02:35:11.795Z] 02:35:11     INFO - Restarting browser for new test group

Comment 1

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

It looks like a race and that we may have to wait until two lines are present in case when the write happens exactly at the time when the test code tries to read it:

[task 2022-06-28T02:35:11.793Z] 02:35:11     INFO -             # Read the port if needed and the debugger address from the
[task 2022-06-28T02:35:11.793Z] 02:35:11     INFO -             # DevToolsActivePort file
[task 2022-06-28T02:35:11.793Z] 02:35:11     INFO -             lines = open(self.cdp_port_file).readlines()
[task 2022-06-28T02:35:11.793Z] 02:35:11     INFO - >           assert len(lines) == 2
[task 2022-06-28T02:35:11.793Z] 02:35:11     INFO - E           assert 0 == 2
[task 2022-06-28T02:35:11.793Z] 02:35:11     INFO - E             +0
[task 2022-06-28T02:35:11.793Z] 02:35:11     INFO - E             -2

Comment 2

[Intermittent Failures Robot]

1 failures in 3312 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-qr: 1
  • debug: 1

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

Comment 3

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 4

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=390296823&repo=mozilla-central

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• windows10-64-2004-shippable-qr: 1
  • opt: 1

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

Comment 6

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 7

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=403841784&repo=autoland

Comment 8

[Intermittent Failures Robot]

1 failures in 3914 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=1776807&startday=2023-01-23&endday=2023-01-29&tree=all

Comment 9

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 10

[BMO Automation]

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

Comment 11

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=408037531&repo=mozilla-central

Comment 12

[Intermittent Failures Robot]

1 failures in 3966 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-shippable-qr: 1
  • opt: 1

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

Comment 13

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 14

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=411350851&repo=try

Comment 15

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform and build breakdown:

• windows11-64-2009-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1776807&startday=2023-04-03&endday=2023-04-09&tree=all

Comment 16

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

• windows11-64-2009-devedition-qr: 1
  • opt: 1

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

Comment 17

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows11-32-2009-qr: 1
  • debug: 1

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

Comment 19

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform and build breakdown:

• windows11-32-2009-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1776807&startday=2023-06-26&endday=2023-07-02&tree=all

Comment 20

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

• windows11-32-2009-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1776807&startday=2023-08-07&endday=2023-08-13&tree=all

Comment 21

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.