Closed
Bug 1630833
Opened 6 years ago
Closed 6 years ago
Intermittent /webdriver/tests/set_timeouts/user_prompts.py | test_ignore[capabilities0-alert] - setup error [TimeoutException: timeout (500): No connection could be made because the target machine actively refused it. (os error 10061)]
Categories
(Remote Protocol :: Marionette, defect, P5)
Tracking
(Not tracked)
RESOLVED
DUPLICATE
of bug 1630132
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=298012299&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/QSWxjp_uSSK5JT63FL6mdw/runs/0/artifacts/public/logs/live_backing.log
...
[task 2020-04-16T23:58:25.089Z] 23:58:25 INFO - PID 3532 | 1587081505084 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-04-16T23:58:25.092Z] 23:58:25 INFO - STDOUT: =================================== ERRORS ====================================
[task 2020-04-16T23:58:25.093Z] 23:58:25 INFO - STDOUT: _____________ ERROR at setup of test_ignore[capabilities0-alert] ______________
[task 2020-04-16T23:58:25.094Z] 23:58:25 INFO - STDOUT: capabilities = {'unhandledPromptBehavior': 'ignore'}
[task 2020-04-16T23:58:25.095Z] 23:58:25 INFO - STDOUT: configuration = {'capabilities': {'moz:firefoxOptions': {'binary': 'Z:\task_1587076946\build\application\firefox\firefox.exe', 'prefs'...form.test', 'network.process.enabled': False, 'toolkit.asyncshutdown.log': True}}}, 'host': '127.0.0.1', 'port': 51791}
[task 2020-04-16T23:58:25.095Z] 23:58:25 INFO - STDOUT: request = <SubRequest 'session' for <Function 'test_ignore[capabilities0-alert]'>>
[task 2020-04-16T23:58:25.095Z] 23:58:25 INFO - STDOUT: @pytest.fixture(scope="function")
[task 2020-04-16T23:58:25.096Z] 23:58:25 INFO - STDOUT: def session(capabilities, configuration, request):
[task 2020-04-16T23:58:25.097Z] 23:58:25 INFO - STDOUT: """Create and start a session for a test that does not itself test session creation.
[task 2020-04-16T23:58:25.098Z] 23:58:25 INFO - STDOUT:
[task 2020-04-16T23:58:25.098Z] 23:58:25 INFO - STDOUT: By default the session will stay open after each test, but we always try to start a
[task 2020-04-16T23:58:25.099Z] 23:58:25 INFO - STDOUT: new one and assume that if that fails there is already a valid session. This makes it
[task 2020-04-16T23:58:25.099Z] 23:58:25 INFO - STDOUT: possible to recover from some errors that might leave the session in a bad state, but
[task 2020-04-16T23:58:25.100Z] 23:58:25 INFO - STDOUT: does not demand that we start a new session per test."""
[task 2020-04-16T23:58:25.101Z] 23:58:25 INFO - STDOUT: global _current_session
[task 2020-04-16T23:58:25.102Z] 23:58:25 INFO - STDOUT:
[task 2020-04-16T23:58:25.102Z] 23:58:25 INFO - STDOUT: # Update configuration capabilities with custom ones from the
[task 2020-04-16T23:58:25.103Z] 23:58:25 INFO - STDOUT: # capabilities fixture, which can be set by tests
[task 2020-04-16T23:58:25.104Z] 23:58:25 INFO - STDOUT: caps = copy.deepcopy(configuration["capabilities"])
[task 2020-04-16T23:58:25.104Z] 23:58:25 INFO - STDOUT: caps.update(capabilities)
[task 2020-04-16T23:58:25.105Z] 23:58:25 INFO - STDOUT: caps = {"alwaysMatch": caps}
[task 2020-04-16T23:58:25.106Z] 23:58:25 INFO - STDOUT:
[task 2020-04-16T23:58:25.106Z] 23:58:25 INFO - STDOUT: # If there is a session with different capabilities active, end it now
[task 2020-04-16T23:58:25.107Z] 23:58:25 INFO - STDOUT: if _current_session is not None and (
[task 2020-04-16T23:58:25.107Z] 23:58:25 INFO - STDOUT: caps != _current_session.requested_capabilities):
[task 2020-04-16T23:58:25.108Z] 23:58:25 INFO - STDOUT: _current_session.end()
[task 2020-04-16T23:58:25.109Z] 23:58:25 INFO - STDOUT: _current_session = None
[task 2020-04-16T23:58:25.109Z] 23:58:25 INFO - STDOUT:
[task 2020-04-16T23:58:25.110Z] 23:58:25 INFO - STDOUT: if _current_session is None:
[task 2020-04-16T23:58:25.111Z] 23:58:25 INFO - STDOUT: _current_session = webdriver.Session(
[task 2020-04-16T23:58:25.112Z] 23:58:25 INFO - STDOUT: configuration["host"],
[task 2020-04-16T23:58:25.113Z] 23:58:25 INFO - STDOUT: configuration["port"],
[task 2020-04-16T23:58:25.113Z] 23:58:25 INFO - STDOUT: capabilities=caps)
[task 2020-04-16T23:58:25.114Z] 23:58:25 INFO - STDOUT: try:
[task 2020-04-16T23:58:25.114Z] 23:58:25 INFO - STDOUT: > _current_session.start()
[task 2020-04-16T23:58:25.115Z] 23:58:25 INFO - STDOUT: capabilities = {'unhandledPromptBehavior': 'ignore'}
[task 2020-04-16T23:58:25.115Z] 23:58:25 INFO - STDOUT: caps = {'alwaysMatch': {'moz:firefoxOptions': {'binary': 'Z:\task_1587076946\build\application\firefox\firefox.exe', 'prefs':...orm.test', 'network.process.enabled': False, 'toolkit.asyncshutdown.log': True}}, 'unhandledPromptBehavior': 'ignore'}}
[task 2020-04-16T23:58:25.118Z] 23:58:25 INFO - STDOUT: configuration = {'capabilities': {'moz:firefoxOptions': {'binary': 'Z:\task_1587076946\build\application\firefox\firefox.exe', 'prefs'...form.test', 'network.process.enabled': False, 'toolkit.asyncshutdown.log': True}}}, 'host': '127.0.0.1', 'port': 51791}
[task 2020-04-16T23:58:25.119Z] 23:58:25 INFO - STDOUT: request = <SubRequest 'session' for <Function 'test_ignore[capabilities0-alert]'>>
[task 2020-04-16T23:58:25.119Z] 23:58:25 INFO - STDOUT: tests\web-platform\tests\webdriver\tests\support\fixtures.py
[task 2020-04-16T23:58:25.120Z] 23:58:25 INFO - STDOUT: :153:
[task 2020-04-16T23:58:25.120Z] 23:58:25 INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2020-04-16T23:58:25.121Z] 23:58:25 INFO - STDOUT: tests\web-platform\tests\tools\webdriver\webdriver\client.py
[task 2020-04-16T23:58:25.122Z] 23:58:25 INFO - STDOUT: :426: in start
[task 2020-04-16T23:58:25.122Z] 23:58:25 INFO - STDOUT: value = self.send_command("POST", "session", body=body)
[task 2020-04-16T23:58:25.123Z] 23:58:25 INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2020-04-16T23:58:25.124Z] 23:58:25 INFO - STDOUT: self = <Session (disconnected)>, method = 'POST', url = 'session'
[task 2020-04-16T23:58:25.124Z] 23:58:25 INFO - STDOUT: body = {'capabilities': {'alwaysMatch': {'moz:firefoxOptions': {'binary': 'Z:\task_1587076946\build\application\firefox\firef...rm.test', 'network.process.enabled': False, 'toolkit.asyncshutdown.log': True}}, 'unhandledPromptBehavior': 'ignore'}}}
[task 2020-04-16T23:58:25.125Z] 23:58:25 INFO - STDOUT: timeout = None
[task 2020-04-16T23:58:25.125Z] 23:58:25 INFO - STDOUT: def send_command(self, method, url, body=None, timeout=None):
[task 2020-04-16T23:58:25.126Z] 23:58:25 INFO - STDOUT: """
[task 2020-04-16T23:58:25.126Z] 23:58:25 INFO - STDOUT: Send a command to the remote end and validate its success.
[task 2020-04-16T23:58:25.127Z] 23:58:25 INFO - STDOUT:
[task 2020-04-16T23:58:25.127Z] 23:58:25 INFO - STDOUT: :param method: HTTP method to use in request.
[task 2020-04-16T23:58:25.128Z] 23:58:25 INFO - STDOUT: :param uri: "Command part" of the HTTP request URL,
[task 2020-04-16T23:58:25.128Z] 23:58:25 INFO - STDOUT: e.g. `window/rect`.
[task 2020-04-16T23:58:25.129Z] 23:58:25 INFO - STDOUT: :param body: Optional body of the HTTP request.
[task 2020-04-16T23:58:25.130Z] 23:58:25 INFO - STDOUT:
[task 2020-04-16T23:58:25.130Z] 23:58:25 INFO - STDOUT: :return: `None` if the HTTP response body was empty, otherwise
[task 2020-04-16T23:58:25.130Z] 23:58:25 INFO - STDOUT: the `value` field returned after parsing the response
[task 2020-04-16T23:58:25.131Z] 23:58:25 INFO - STDOUT: body as JSON.
[task 2020-04-16T23:58:25.132Z] 23:58:25 INFO - STDOUT:
[task 2020-04-16T23:58:25.132Z] 23:58:25 INFO - STDOUT: :raises error.WebDriverException: If the remote end returns
[task 2020-04-16T23:58:25.133Z] 23:58:25 INFO - STDOUT: an error.
[task 2020-04-16T23:58:25.133Z] 23:58:25 INFO - STDOUT: :raises ValueError: If the response body does not contain a
[task 2020-04-16T23:58:25.133Z] 23:58:25 INFO - STDOUT: `value` key.
[task 2020-04-16T23:58:25.134Z] 23:58:25 INFO - STDOUT: """
[task 2020-04-16T23:58:25.135Z] 23:58:25 INFO - STDOUT: response = self.transport.send(
[task 2020-04-16T23:58:25.136Z] 23:58:25 INFO - STDOUT: method, url, body,
[task 2020-04-16T23:58:25.136Z] 23:58:25 INFO - STDOUT: encoder=protocol.Encoder, decoder=protocol.Decoder,
[task 2020-04-16T23:58:25.137Z] 23:58:25 INFO - STDOUT: session=self, timeout=timeout)
[task 2020-04-16T23:58:25.137Z] 23:58:25 INFO - STDOUT:
[task 2020-04-16T23:58:25.138Z] 23:58:25 INFO - STDOUT: if response.status != 200:
[task 2020-04-16T23:58:25.139Z] 23:58:25 INFO - STDOUT: err = error.from_response(response)
[task 2020-04-16T23:58:25.140Z] 23:58:25 INFO - STDOUT:
[task 2020-04-16T23:58:25.140Z] 23:58:25 INFO - STDOUT: if isinstance(err, error.InvalidSessionIdException):
[task 2020-04-16T23:58:25.140Z] 23:58:25 INFO - STDOUT: # The driver could have already been deleted the session.
[task 2020-04-16T23:58:25.141Z] 23:58:25 INFO - STDOUT: self.session_id = None
[task 2020-04-16T23:58:25.141Z] 23:58:25 INFO - STDOUT:
[task 2020-04-16T23:58:25.141Z] 23:58:25 INFO - STDOUT: > raise err
[task 2020-04-16T23:58:25.142Z] 23:58:25 INFO - STDOUT: E TimeoutException: timeout (500): No connection could be made because the target machine actively refused it. (os error 10061)
[task 2020-04-16T23:58:25.143Z] 23:58:25 INFO - STDOUT: body = {'capabilities': {'alwaysMatch': {'moz:firefoxOptions': {'binary': 'Z:\task_1587076946\build\application\firefox\firef...rm.test', 'network.process.enabled': False, 'toolkit.asyncshutdown.log': True}}, 'unhandledPromptBehavior': 'ignore'}}}
[task 2020-04-16T23:58:25.143Z] 23:58:25 INFO - STDOUT: err = <TimeoutException http_status=500>
[task 2020-04-16T23:58:25.144Z] 23:58:25 INFO - STDOUT: method = 'POST'
[task 2020-04-16T23:58:25.144Z] 23:58:25 INFO - STDOUT: response = <Response status=500 error=<TimeoutException http_status=500>>
[task 2020-04-16T23:58:25.145Z] 23:58:25 INFO - STDOUT: self = <Session (disconnected)>
[task 2020-04-16T23:58:25.146Z] 23:58:25 INFO - STDOUT: timeout = None
[task 2020-04-16T23:58:25.146Z] 23:58:25 INFO - STDOUT: url = 'session'
[task 2020-04-16T23:58:25.147Z] 23:58:25 INFO - STDOUT: tests\web-platform\tests\tools\webdriver\webdriver\client.py
[task 2020-04-16T23:58:25.147Z] 23:58:25 INFO - STDOUT: :477: TimeoutException
[task 2020-04-16T23:58:25.148Z] 23:58:25 INFO - STDOUT: ============================== warnings summary ===============================
[task 2020-04-16T23:58:25.148Z] 23:58:25 INFO - STDOUT: <undetermined location>
[task 2020-04-16T23:58:25.149Z] 23:58:25 INFO - STDOUT: Module already imported so cannot be rewritten: mozlog
[task 2020-04-16T23:58:25.150Z] 23:58:25 INFO - STDOUT: Module already imported so cannot be rewritten: tests.support.fixtures
[task 2020-04-16T23:58:25.150Z] 23:58:25 INFO - STDOUT: -- Docs: http://doc.pytest.org/en/latest/warnings.html
[task 2020-04-16T23:58:25.150Z] 23:58:25 INFO - STDOUT: =============== 17 passed, 2 warnings, 1 error in 83.45 seconds ===============
[task 2020-04-16T23:58:25.190Z] 23:58:25 INFO -
[task 2020-04-16T23:58:25.190Z] 23:58:25 INFO - TEST-PASS | /webdriver/tests/set_timeouts/user_prompts.py | test_accept[capabilities0-prompt]
[task 2020-04-16T23:58:25.190Z] 23:58:25 INFO - TEST-PASS | /webdriver/tests/set_timeouts/user_prompts.py | test_accept_and_notify[capabilities0-alert]
[task 2020-04-16T23:58:25.190Z] 23:58:25 INFO - TEST-PASS | /webdriver/tests/set_timeouts/user_prompts.py | test_accept_and_notify[capabilities0-confirm]
[task 2020-04-16T23:58:25.190Z] 23:58:25 INFO - TEST-PASS | /webdriver/tests/set_timeouts/user_prompts.py | test_accept_and_notify[capabilities0-prompt]
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO - TEST-PASS | /webdriver/tests/set_timeouts/user_prompts.py | test_dismiss[capabilities0-alert]
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO - TEST-PASS | /webdriver/tests/set_timeouts/user_prompts.py | test_dismiss[capabilities0-confirm]
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO - TEST-PASS | /webdriver/tests/set_timeouts/user_prompts.py | test_dismiss[capabilities0-prompt]
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO - TEST-PASS | /webdriver/tests/set_timeouts/user_prompts.py | test_dismiss_and_notify[capabilities0-alert]
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO - TEST-PASS | /webdriver/tests/set_timeouts/user_prompts.py | test_dismiss_and_notify[capabilities0-confirm]
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO - TEST-PASS | /webdriver/tests/set_timeouts/user_prompts.py | test_dismiss_and_notify[capabilities0-prompt]
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO - TEST-UNEXPECTED-ERROR | /webdriver/tests/set_timeouts/user_prompts.py | test_ignore[capabilities0-alert] - setup error
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO - capabilities = {'unhandledPromptBehavior': 'ignore'}
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO - configuration = {'capabilities': {'moz:firefoxOptions': {'binary': 'Z:\task_1587076946\build\application\firefox\firefox.exe', 'prefs'...form.test', 'network.process.enabled': False, 'toolkit.asyncshutdown.log': True}}}, 'host': '127.0.0.1', 'port': 51791}
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO - request = <SubRequest 'session' for <Function 'test_ignore[capabilities0-alert]'>>
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO -
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO - @pytest.fixture(scope="function")
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO - def session(capabilities, configuration, request):
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO - """Create and start a session for a test that does not itself test session creation.
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO -
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO - By default the session will stay open after each test, but we always try to start a
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO - new one and assume that if that fails there is already a valid session. This makes it
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO - possible to recover from some errors that might leave the session in a bad state, but
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO - does not demand that we start a new session per test."""
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO - global _current_session
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO -
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO - # Update configuration capabilities with custom ones from the
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO - # capabilities fixture, which can be set by tests
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO - caps = copy.deepcopy(configuration["capabilities"])
[task 2020-04-16T23:58:25.191Z] 23:58:25 INFO - caps.update(capabilities)
[task 2020-04-16T23:58:25.192Z] 23:58:25 INFO - caps = {"alwaysMatch": caps}
[task 2020-04-16T23:58:25.192Z] 23:58:25 INFO -
[task 2020-04-16T23:58:25.192Z] 23:58:25 INFO - # If there is a session with different capabilities active, end it now
[task 2020-04-16T23:58:25.192Z] 23:58:25 INFO - if _current_session is not None and (
[task 2020-04-16T23:58:25.192Z] 23:58:25 INFO - caps != _current_session.requested_capabilities):
[task 2020-04-16T23:58:25.192Z] 23:58:25 INFO - _current_session.end()
[task 2020-04-16T23:58:25.192Z] 23:58:25 INFO - _current_session = None
[task 2020-04-16T23:58:25.192Z] 23:58:25 INFO -
[task 2020-04-16T23:58:25.193Z] 23:58:25 INFO - if _current_session is None:
[task 2020-04-16T23:58:25.193Z] 23:58:25 INFO - _current_session = webdriver.Session(
[task 2020-04-16T23:58:25.193Z] 23:58:25 INFO - configuration["host"],
[task 2020-04-16T23:58:25.193Z] 23:58:25 INFO - configuration["port"],
[task 2020-04-16T23:58:25.193Z] 23:58:25 INFO - capabilities=caps)
[task 2020-04-16T23:58:25.193Z] 23:58:25 INFO - try:
[task 2020-04-16T23:58:25.193Z] 23:58:25 INFO - > _current_session.start()
[task 2020-04-16T23:58:25.193Z] 23:58:25 INFO -
[task 2020-04-16T23:58:25.193Z] 23:58:25 INFO - capabilities = {'unhandledPromptBehavior': 'ignore'}
[task 2020-04-16T23:58:25.194Z] 23:58:25 INFO - caps = {'alwaysMatch': {'moz:firefoxOptions': {'binary': 'Z:\task_1587076946\build\application\firefox\firefox.exe', 'prefs':...orm.test', 'network.process.enabled': False, 'toolkit.asyncshutdown.log': True}}, 'unhandledPromptBehavior': 'ignore'}}
[task 2020-04-16T23:58:25.194Z] 23:58:25 INFO - configuration = {'capabilities': {'moz:firefoxOptions': {'binary': 'Z:\task_1587076946\build\application\firefox\firefox.exe', 'prefs'...form.test', 'network.process.enabled': False, 'toolkit.asyncshutdown.log': True}}}, 'host': '127.0.0.1', 'port': 51791}
[task 2020-04-16T23:58:25.194Z] 23:58:25 INFO - request = <SubRequest 'session' for <Function 'test_ignore[capabilities0-alert]'>>
[task 2020-04-16T23:58:25.194Z] 23:58:25 INFO -
[task 2020-04-16T23:58:25.194Z] 23:58:25 INFO - tests\web-platform\tests\webdriver\tests\support\fixtures.py:153:
[task 2020-04-16T23:58:25.194Z] 23:58:25 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2020-04-16T23:58:25.194Z] 23:58:25 INFO - tests\web-platform\tests\tools\webdriver\webdriver\client.py:426: in start
[task 2020-04-16T23:58:25.194Z] 23:58:25 INFO - value = self.send_command("POST", "session", body=body)
[task 2020-04-16T23:58:25.194Z] 23:58:25 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2020-04-16T23:58:25.194Z] 23:58:25 INFO -
[task 2020-04-16T23:58:25.195Z] 23:58:25 INFO - self = <Session (disconnected)>, method = 'POST', url = 'session'
[task 2020-04-16T23:58:25.195Z] 23:58:25 INFO - body = {'capabilities': {'alwaysMatch': {'moz:firefoxOptions': {'binary': 'Z:\task_1587076946\build\application\firefox\firef...rm.test', 'network.process.enabled': False, 'toolkit.asyncshutdown.log': True}}, 'unhandledPromptBehavior': 'ignore'}}}
[task 2020-04-16T23:58:25.195Z] 23:58:25 INFO - timeout = None
...
The reason here is:
TimeoutException: timeout (500): No connection could be made because the target machine actively refused it. (os error 10061)
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → DUPLICATE
Summary: Intermittent /webdriver/tests/set_timeouts/user_prompts.py | test_ignore[capabilities0-alert] - setup error → Intermittent /webdriver/tests/set_timeouts/user_prompts.py | test_ignore[capabilities0-alert] - setup error [TimeoutException: timeout (500): No connection could be made because the target machine actively refused it. (os error 10061)]
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 3•3 years ago
|
||
Moving bug to Remote Protocol::Marionette component per bug 1815831.
Component: geckodriver → Marionette
Product: Testing → Remote Protocol
You need to log in
before you can comment on or make changes to this bug.
Description
•