Closed Bug 1836902 Opened 1 years ago Closed 1 years ago

Intermittent mozilla/tests/webdriver/classic/protocol/marionette_port.py | test_marionette_port_outdated_active_port_file - ConnectionRefusedError: [Errno 111] Connection refused

Categories

(Remote Protocol :: Marionette, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED DUPLICATE of bug 1571426

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2023-06-06T05:27:01.732Z] 05:27:01     INFO - STDOUT: FAILED tests/web-platform/mozilla/tests/webdriver/classic/protocol/marionette_port.py::test_marionette_port_outdated_active_port_file
[task 2023-06-06T05:27:01.733Z] 05:27:01     INFO - STDOUT: ========================= 1 failed, 2 passed in 15.01s =========================
[task 2023-06-06T05:27:01.744Z] 05:27:01     INFO - 
[task 2023-06-06T05:27:01.745Z] 05:27:01     INFO - TEST-PASS | /_mozilla/webdriver/classic/protocol/marionette_port.py | test_marionette_port[system allocated] 
[task 2023-06-06T05:27:01.745Z] 05:27:01     INFO - TEST-PASS | /_mozilla/webdriver/classic/protocol/marionette_port.py | test_marionette_port[fixed] 
[task 2023-06-06T05:27:01.746Z] 05:27:01     INFO - TEST-UNEXPECTED-FAIL | /_mozilla/webdriver/classic/protocol/marionette_port.py | test_marionette_port_outdated_active_port_file - ConnectionRefusedError: [Errno 111] Connection refused
[task 2023-06-06T05:27:01.746Z] 05:27:01     INFO - configuration = {'browser': {'args': ['--profile', '/tmp/tmptbdxp0w_.mozrunner'], 'binary': '/builds/worker/workspace/build/applicatio...{'moz:firefoxOptions': {'args': ['--profile', '/tmp/tmptbdxp0w_.mozrunner']}}, 'host': '127.0.0.1', 'port': 60034, ...}
[task 2023-06-06T05:27:01.746Z] 05:27:01     INFO - geckodriver = <function geckodriver.<locals>._geckodriver at 0x7fcca10580e0>
[task 2023-06-06T05:27:01.747Z] 05:27:01     INFO - custom_profile = <mozprofile.profile.Profile object at 0x7fcca0fddd50>
[task 2023-06-06T05:27:01.747Z] 05:27:01     INFO - 
[task 2023-06-06T05:27:01.747Z] 05:27:01     INFO -     def test_marionette_port_outdated_active_port_file(
[task 2023-06-06T05:27:01.748Z] 05:27:01     INFO -         configuration, geckodriver, custom_profile
[task 2023-06-06T05:27:01.748Z] 05:27:01     INFO -     ):
[task 2023-06-06T05:27:01.748Z] 05:27:01     INFO -         config = deepcopy(configuration)
[task 2023-06-06T05:27:01.749Z] 05:27:01     INFO -         extra_args = ["--marionette-port", "0"]
[task 2023-06-06T05:27:01.749Z] 05:27:01     INFO -     
[task 2023-06-06T05:27:01.749Z] 05:27:01     INFO -         # Prepare a Marionette active port file that contains a port which will
[task 2023-06-06T05:27:01.750Z] 05:27:01     INFO -         # never be used when requesting a system allocated port.
[task 2023-06-06T05:27:01.750Z] 05:27:01     INFO -         active_port_file = os.path.join(custom_profile.profile, "MarionetteActivePort")
[task 2023-06-06T05:27:01.751Z] 05:27:01     INFO -         with open(active_port_file, "wb") as f:
[task 2023-06-06T05:27:01.751Z] 05:27:01     INFO -             f.write(b"53")
[task 2023-06-06T05:27:01.751Z] 05:27:01     INFO -     
[task 2023-06-06T05:27:01.752Z] 05:27:01     INFO -         config["capabilities"]["moz:firefoxOptions"]["args"] = [
[task 2023-06-06T05:27:01.752Z] 05:27:01     INFO -             "--profile",
[task 2023-06-06T05:27:01.753Z] 05:27:01     INFO -             custom_profile.profile,
[task 2023-06-06T05:27:01.753Z] 05:27:01     INFO -         ]
[task 2023-06-06T05:27:01.753Z] 05:27:01     INFO -     
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO -         driver = geckodriver(config=config, extra_args=extra_args)
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO -     
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO - >       driver.new_session()
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO - 
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO - active_port_file = '/tmp/tmpx4nxxon1/MarionetteActivePort'
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO - config     = {'browser': {'args': ['--profile', '/tmp/tmptbdxp0w_.mozrunner'],
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO -              'binary': '/builds/worker/workspace/build/application/firefox/firefox'},
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO -  'capabilities': {'moz:firefoxOptions': {'args': ['--profile',
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO -                                                   '/tmp/tmpx4nxxon1']}},
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO -  'host': '127.0.0.1',
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO -  'port': 60034,
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO -  'timeout_multiplier': 3,
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO -  'webdriver': {'args': ['-vv',
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO -                         '--binary',
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO -                         '/builds/worker/workspace/build/application/firefox/firefox'],
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO -                'binary': '/builds/worker/fetches/geckodriver'}}
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO - configuration = {'browser': {'args': ['--profile', '/tmp/tmptbdxp0w_.mozrunner'],
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO -              'binary': '/builds/worker/workspace/build/application/firefox/firefox'},
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO -  'capabilities': {'moz:firefoxOptions': {'args': ['--profile',
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO -                                                   '/tmp/tmptbdxp0w_.mozrunner']}},
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO -  'host': '127.0.0.1',
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO -  'port': 60034,
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO -  'timeout_multiplier': 3,
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO -  'webdriver': {'args': ['-vv',
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO -                         '--binary',
[task 2023-06-06T05:27:01.754Z] 05:27:01     INFO -                         '/builds/worker/workspace/build/application/firefox/firefox'],
[task 2023-06-06T05:27:01.755Z] 05:27:01     INFO -                'binary': '/builds/worker/fetches/geckodriver'}}
[task 2023-06-06T05:27:01.755Z] 05:27:01     INFO - custom_profile = <mozprofile.profile.Profile object at 0x7fcca0fddd50>
[task 2023-06-06T05:27:01.755Z] 05:27:01     INFO - driver     = <support.fixtures.Geckodriver object at 0x7fcca0fd7210>
[task 2023-06-06T05:27:01.755Z] 05:27:01     INFO - extra_args = ['--marionette-port', '0']
[task 2023-06-06T05:27:01.755Z] 05:27:01     INFO - f          = <_io.BufferedWriter name='/tmp/tmpx4nxxon1/MarionetteActivePort'>
[task 2023-06-06T05:27:01.755Z] 05:27:01     INFO - geckodriver = <function geckodriver.<locals>._geckodriver at 0x7fcca10580e0>
[task 2023-06-06T05:27:01.755Z] 05:27:01     INFO - 
[task 2023-06-06T05:27:01.755Z] 05:27:01     INFO - tests/web-platform/mozilla/tests/webdriver/classic/protocol/marionette_port.py:35: 
[task 2023-06-06T05:27:01.755Z] 05:27:01     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2023-06-06T05:27:01.755Z] 05:27:01     INFO - tests/web-platform/mozilla/tests/webdriver/support/fixtures.py:253: in new_session
[task 2023-06-06T05:27:01.755Z] 05:27:01     INFO -     self.session.start()
[task 2023-06-06T05:27:01.755Z] 05:27:01     INFO -         self       = <support.fixtures.Geckodriver object at 0x7fcca0fd7210>
[task 2023-06-06T05:27:01.755Z] 05:27:01     INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:558: in start
[task 2023-06-06T05:27:01.755Z] 05:27:01     INFO -     value = self.send_command("POST", "session", body=body)
[task 2023-06-06T05:27:01.755Z] 05:27:01     INFO -         body       = {'capabilities': {'alwaysMatch': {'moz:firefoxOptions': {'args': ['--profile',
[task 2023-06-06T05:27:01.755Z] 05:27:01     INFO -                                                                   '/tmp/tmpx4nxxon1']}}}}
[task 2023-06-06T05:27:01.755Z] 05:27:01     INFO -         self       = <Session (disconnected)>
[task 2023-06-06T05:27:01.756Z] 05:27:01     INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:613: in send_command
[task 2023-06-06T05:27:01.756Z] 05:27:01     INFO -     session=self, timeout=timeout)
[task 2023-06-06T05:27:01.756Z] 05:27:01     INFO -         body       = {'capabilities': {'alwaysMatch': {'moz:firefoxOptions': {'args': ['--profile',
[task 2023-06-06T05:27:01.756Z] 05:27:01     INFO -                                                                   '/tmp/tmpx4nxxon1']}}}}
[task 2023-06-06T05:27:01.756Z] 05:27:01     INFO -         method     = 'POST'
[task 2023-06-06T05:27:01.756Z] 05:27:01     INFO -         self       = <Session (disconnected)>
[task 2023-06-06T05:27:01.757Z] 05:27:01     INFO -         timeout    = None
[task 2023-06-06T05:27:01.757Z] 05:27:01     INFO -         url        = 'session'
[task 2023-06-06T05:27:01.757Z] 05:27:01     INFO - tests/web-platform/tests/tools/webdriver/webdriver/transport.py:234: in send
[task 2023-06-06T05:27:01.758Z] 05:27:01     INFO -     response = self._request(method, uri, payload, headers, timeout=None)
[task 2023-06-06T05:27:01.758Z] 05:27:01     INFO -         body       = {'capabilities': {'alwaysMatch': {'moz:firefoxOptions': {'args': ['--profile',
[task 2023-06-06T05:27:01.758Z] 05:27:01     INFO -                                                                   '/tmp/tmpx4nxxon1']}}}}
[task 2023-06-06T05:27:01.758Z] 05:27:01     INFO -         codec_kwargs = {'session': <Session (disconnected)>}
[task 2023-06-06T05:27:01.758Z] 05:27:01     INFO -         decoder    = <class 'webdriver.protocol.Decoder'>
[task 2023-06-06T05:27:01.759Z] 05:27:01     INFO -         encoder    = <class 'webdriver.protocol.Encoder'>
[task 2023-06-06T05:27:01.759Z] 05:27:01     INFO -         headers    = None
[task 2023-06-06T05:27:01.759Z] 05:27:01     INFO -         method     = 'POST'
[task 2023-06-06T05:27:01.759Z] 05:27:01     INFO -         payload    = ('{"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"args": '
[task 2023-06-06T05:27:01.759Z] 05:27:01     INFO -  '["--profile", "/tmp/tmpx4nxxon1"]}}}}')
[task 2023-06-06T05:27:01.759Z] 05:27:01     INFO -         self       = <webdriver.transport.HTTPWireProtocol object at 0x7fcca0fd7050>
[task 2023-06-06T05:27:01.759Z] 05:27:01     INFO -         timeout    = None
[task 2023-06-06T05:27:01.760Z] 05:27:01     INFO -         uri        = 'session'
[task 2023-06-06T05:27:01.760Z] 05:27:01     INFO - tests/web-platform/tests/tools/webdriver/webdriver/transport.py:251: in _request
[task 2023-06-06T05:27:01.760Z] 05:27:01     INFO -     self.connection.request(method, url, payload, headers)
[task 2023-06-06T05:27:01.760Z] 05:27:01     INFO -         headers    = {'Connection': 'keep-alive'}
[task 2023-06-06T05:27:01.760Z] 05:27:01     INFO -         method     = 'POST'
[task 2023-06-06T05:27:01.761Z] 05:27:01     INFO -         payload    = (b'{"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"args": ["--profile'
[task 2023-06-06T05:27:01.761Z] 05:27:01     INFO -  b'", "/tmp/tmpx4nxxon1"]}}}}')
[task 2023-06-06T05:27:01.761Z] 05:27:01     INFO -         self       = <webdriver.transport.HTTPWireProtocol object at 0x7fcca0fd7050>
[task 2023-06-06T05:27:01.761Z] 05:27:01     INFO -         timeout    = None
[task 2023-06-06T05:27:01.761Z] 05:27:01     INFO -         uri        = 'session'
[task 2023-06-06T05:27:01.762Z] 05:27:01     INFO -         url        = '/session'
[task 2023-06-06T05:27:01.762Z] 05:27:01     INFO - /usr/lib/python3.7/http/client.py:1256: in request
[task 2023-06-06T05:27:01.762Z] 05:27:01     INFO -     self._send_request(method, url, body, headers, encode_chunked)
[task 2023-06-06T05:27:01.762Z] 05:27:01     INFO -         body       = (b'{"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"args": ["--profile'
[task 2023-06-06T05:27:01.762Z] 05:27:01     INFO -  b'", "/tmp/tmpx4nxxon1"]}}}}')
[task 2023-06-06T05:27:01.763Z] 05:27:01     INFO -         encode_chunked = False
[task 2023-06-06T05:27:01.763Z] 05:27:01     INFO -         headers    = {'Connection': 'keep-alive'}
[task 2023-06-06T05:27:01.763Z] 05:27:01     INFO -         method     = 'POST'
[task 2023-06-06T05:27:01.763Z] 05:27:01     INFO -         self       = <http.client.HTTPConnection object at 0x7fcca0fdd050>
[task 2023-06-06T05:27:01.763Z] 05:27:01     INFO -         url        = '/session'
[task 2023-06-06T05:27:01.764Z] 05:27:01     INFO - /usr/lib/python3.7/http/client.py:1302: in _send_request
[task 2023-06-06T05:27:01.764Z] 05:27:01     INFO -     self.endheaders(body, encode_chunked=encode_chunked)
[task 2023-06-06T05:27:01.764Z] 05:27:01     INFO -         body       = (b'{"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"args": ["--profile'
[task 2023-06-06T05:27:01.764Z] 05:27:01     INFO -  b'", "/tmp/tmpx4nxxon1"]}}}}')
[task 2023-06-06T05:27:01.765Z] 05:27:01     INFO -         content_length = 102
[task 2023-06-06T05:27:01.765Z] 05:27:01     INFO -         encode_chunked = False
[task 2023-06-06T05:27:01.765Z] 05:27:01     INFO -         hdr        = 'Connection'
[task 2023-06-06T05:27:01.766Z] 05:27:01     INFO -         header_names = frozenset({'connection'})
[task 2023-06-06T05:27:01.766Z] 05:27:01     INFO -         headers    = {'Connection': 'keep-alive'}
[task 2023-06-06T05:27:01.766Z] 05:27:01     INFO -         method     = 'POST'
[task 2023-06-06T05:27:01.766Z] 05:27:01     INFO -         self       = <http.client.HTTPConnection object at 0x7fcca0fdd050>
[task 2023-06-06T05:27:01.767Z] 05:27:01     INFO -         skips      = {}
[task 2023-06-06T05:27:01.767Z] 05:27:01     INFO -         url        = '/session'
[task 2023-06-06T05:27:01.767Z] 05:27:01     INFO -         value      = 'keep-alive'
[task 2023-06-06T05:27:01.767Z] 05:27:01     INFO - /usr/lib/python3.7/http/client.py:1251: in endheaders
[task 2023-06-06T05:27:01.767Z] 05:27:01     INFO -     self._send_output(message_body, encode_chunked=encode_chunked)
[task 2023-06-06T05:27:01.767Z] 05:27:01     INFO -         encode_chunked = False
[task 2023-06-06T05:27:01.767Z] 05:27:01     INFO -         message_body = (b'{"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"args": ["--profile'
[task 2023-06-06T05:27:01.767Z] 05:27:01     INFO -  b'", "/tmp/tmpx4nxxon1"]}}}}')
[task 2023-06-06T05:27:01.767Z] 05:27:01     INFO -         self       = <http.client.HTTPConnection object at 0x7fcca0fdd050>
[task 2023-06-06T05:27:01.767Z] 05:27:01     INFO - /usr/lib/python3.7/http/client.py:1030: in _send_output
[task 2023-06-06T05:27:01.768Z] 05:27:01     INFO -     self.send(msg)
[task 2023-06-06T05:27:01.768Z] 05:27:01     INFO -         encode_chunked = False
[task 2023-06-06T05:27:01.768Z] 05:27:01     INFO -         message_body = (b'{"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"args": ["--profile'
[task 2023-06-06T05:27:01.768Z] 05:27:01     INFO -  b'", "/tmp/tmpx4nxxon1"]}}}}')
[task 2023-06-06T05:27:01.768Z] 05:27:01     INFO -         msg        = (b'POST /session HTTP/1.1\r\nHost: 127.0.0.1:42622\r\nAccept-Encoding: identity'
[task 2023-06-06T05:27:01.768Z] 05:27:01     INFO -  b'\r\nContent-Length: 102\r\nConnection: keep-alive\r\n\r\n')
[task 2023-06-06T05:27:01.768Z] 05:27:01     INFO -         self       = <http.client.HTTPConnection object at 0x7fcca0fdd050>
[task 2023-06-06T05:27:01.768Z] 05:27:01     INFO - /usr/lib/python3.7/http/client.py:970: in send
[task 2023-06-06T05:27:01.768Z] 05:27:01     INFO -     self.connect()
[task 2023-06-06T05:27:01.768Z] 05:27:01     INFO -         data       = (b'POST /session HTTP/1.1\r\nHost: 127.0.0.1:42622\r\nAccept-Encoding: identity'
[task 2023-06-06T05:27:01.768Z] 05:27:01     INFO -  b'\r\nContent-Length: 102\r\nConnection: keep-alive\r\n\r\n')
[task 2023-06-06T05:27:01.768Z] 05:27:01     INFO -         self       = <http.client.HTTPConnection object at 0x7fcca0fdd050>
[task 2023-06-06T05:27:01.768Z] 05:27:01     INFO - /usr/lib/python3.7/http/client.py:942: in connect
[task 2023-06-06T05:27:01.768Z] 05:27:01     INFO -     (self.host,self.port), self.timeout, self.source_address)
[task 2023-06-06T05:27:01.769Z] 05:27:01     INFO -         self       = <http.client.HTTPConnection object at 0x7fcca0fdd050>
[task 2023-06-06T05:27:01.769Z] 05:27:01     INFO - /usr/lib/python3.7/socket.py:727: in create_connection
[task 2023-06-06T05:27:01.769Z] 05:27:01     INFO -     raise err
[task 2023-06-06T05:27:01.769Z] 05:27:01     INFO -         address    = ('127.0.0.1', 42622)
[task 2023-06-06T05:27:01.769Z] 05:27:01     INFO -         af         = <AddressFamily.AF_INET: 2>
[task 2023-06-06T05:27:01.769Z] 05:27:01     INFO -         canonname  = ''
[task 2023-06-06T05:27:01.769Z] 05:27:01     INFO -         err        = ConnectionRefusedError(111, 'Connection refused')
[task 2023-06-06T05:27:01.769Z] 05:27:01     INFO -         host       = '127.0.0.1'
[task 2023-06-06T05:27:01.769Z] 05:27:01     INFO -         port       = 42622
[task 2023-06-06T05:27:01.769Z] 05:27:01     INFO -         proto      = 6
[task 2023-06-06T05:27:01.769Z] 05:27:01     INFO -         res        = (<AddressFamily.AF_INET: 2>,
[task 2023-06-06T05:27:01.769Z] 05:27:01     INFO -  <SocketKind.SOCK_STREAM: 1>,
[task 2023-06-06T05:27:01.769Z] 05:27:01     INFO -  6,
[task 2023-06-06T05:27:01.769Z] 05:27:01     INFO -  '',
[task 2023-06-06T05:27:01.770Z] 05:27:01     INFO -  ('127.0.0.1', 42622))
[task 2023-06-06T05:27:01.770Z] 05:27:01     INFO -         sa         = ('127.0.0.1', 42622)
[task 2023-06-06T05:27:01.770Z] 05:27:01     INFO -         sock       = <socket.socket [closed] fd=-1, family=AddressFamily.AF_INET, type=SocketKind.SOCK_STREAM, proto=6>
[task 2023-06-06T05:27:01.770Z] 05:27:01     INFO -         socktype   = <SocketKind.SOCK_STREAM: 1>
[task 2023-06-06T05:27:01.770Z] 05:27:01     INFO -         source_address = None
[task 2023-06-06T05:27:01.770Z] 05:27:01     INFO -         timeout    = <object object at 0x7fccadb5cd60>
Status: NEW → RESOLVED
Closed: 1 years ago
Duplicate of bug: 1571426
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.