Closed Bug 1589296 Opened 6 years ago Closed 6 years ago

Intermittent Tier 2 /webdriver/tests/delete_cookie/delete.py | test_null_response_value - setup error | test_no_browsing_context - setup error | test_unknown_cookie - setup error

Categories

(Remote Protocol :: Marionette, defect, P5)

Product:
Remote Protocol
Component:
Marionette
Version:
Version 3
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED DUPLICATE of bug 1576061

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=271642392&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/Mm1H-lHiQUCdOuKx8jZfHg/runs/0/artifacts/public/logs/live_backing.log

[task 2019-10-17T04:28:14.554Z] 04:28:14 INFO - TEST-START | /webdriver/tests/delete_cookie/delete.py
[task 2019-10-17T04:28:14.564Z] 04:28:14 INFO - PID 2370 | /builds/worker/workspace/build/tests/bin/geckodriver: error: Address already in use (os error 98)
[task 2019-10-17T04:28:14.565Z] 04:28:14 INFO - PID 2370 | geckodriver 0.26.0 (9e3ef2b6a889 2019-10-17 06:44 +0300)
[task 2019-10-17T04:28:14.565Z] 04:28:14 INFO - PID 2370 | WebDriver implementation for Firefox
[task 2019-10-17T04:28:14.566Z] 04:28:14 INFO - PID 2370 |
[task 2019-10-17T04:28:14.566Z] 04:28:14 INFO - PID 2370 | USAGE:
[task 2019-10-17T04:28:14.567Z] 04:28:14 INFO - PID 2370 | geckodriver [FLAGS] [OPTIONS]
[task 2019-10-17T04:28:14.567Z] 04:28:14 INFO - PID 2370 |
[task 2019-10-17T04:28:14.568Z] 04:28:14 INFO - PID 2370 | FLAGS:
[task 2019-10-17T04:28:14.568Z] 04:28:14 INFO - PID 2370 | --connect-existing Connect to an existing Firefox instance
[task 2019-10-17T04:28:14.568Z] 04:28:14 INFO - PID 2370 | -h, --help Prints this message
[task 2019-10-17T04:28:14.569Z] 04:28:14 INFO - PID 2370 | --jsdebugger Attach browser toolbox debugger for Firefox
[task 2019-10-17T04:28:14.569Z] 04:28:14 INFO - PID 2370 | -v Log level verbosity (-v for debug and -vv for trace level)
[task 2019-10-17T04:28:14.569Z] 04:28:14 INFO - PID 2370 | -V, --version Prints version and copying information
[task 2019-10-17T04:28:14.570Z] 04:28:14 INFO - PID 2370 |
[task 2019-10-17T04:28:14.570Z] 04:28:14 INFO - PID 2370 | OPTIONS:
[task 2019-10-17T04:28:14.571Z] 04:28:14 INFO - PID 2370 | -b, --binary <BINARY> Path to the Firefox binary
[task 2019-10-17T04:28:14.571Z] 04:28:14 INFO - PID 2370 | --log <LEVEL> Set Gecko log level [possible values: fatal, error, warn, info, config, debug,
[task 2019-10-17T04:28:14.571Z] 04:28:14 INFO - PID 2370 | trace]
[task 2019-10-17T04:28:14.572Z] 04:28:14 INFO - PID 2370 | --marionette-host <HOST> Host to use to connect to Gecko [default: 127.0.0.1]
[task 2019-10-17T04:28:14.573Z] 04:28:14 INFO - PID 2370 | --marionette-port <PORT> Port to use to connect to Gecko [default: system-allocated port]
[task 2019-10-17T04:28:14.574Z] 04:28:14 INFO - PID 2370 | --host <HOST> Host IP to use for WebDriver server [default: 127.0.0.1]
[task 2019-10-17T04:28:14.574Z] 04:28:14 INFO - PID 2370 | -p, --port <PORT> Port to use for WebDriver server [default: 4444]
[task 2019-10-17T04:28:14.792Z] 04:28:14 INFO - STDOUT: ============================= test session starts ==============================
[task 2019-10-17T04:28:14.793Z] 04:28:14 INFO - STDOUT: platform linux2 -- Python 2.7.12, pytest-unknown, py-1.5.2, pluggy-0.5.3.dev -- /builds/worker/workspace/build/venv/bin/python
[task 2019-10-17T04:28:14.795Z] 04:28:14 INFO - STDOUT: rootdir: /builds/worker/workspace/build, inifile:
[task 2019-10-17T04:28:14.796Z] 04:28:14 INFO - STDOUT: collecting ...
[task 2019-10-17T04:28:14.812Z] 04:28:14 INFO - STDOUT: collected 3 items
[task 2019-10-17T04:28:14.812Z] 04:28:14 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/delete_cookie/delete.py::test_null_response_value
[task 2019-10-17T04:28:14.941Z] 04:28:14 INFO - STDOUT: ERROR
[task 2019-10-17T04:28:14.942Z] 04:28:14 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/delete_cookie/delete.py::test_no_browsing_context
[task 2019-10-17T04:28:14.982Z] 04:28:14 INFO - STDOUT: ERROR
[task 2019-10-17T04:28:14.990Z] 04:28:14 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/delete_cookie/delete.py::test_unknown_cookie
[task 2019-10-17T04:28:15.032Z] 04:28:15 INFO - STDOUT: ERROR
[task 2019-10-17T04:28:15.034Z] 04:28:15 INFO - STDOUT: ==================================== ERRORS ====================================
[task 2019-10-17T04:28:15.034Z] 04:28:15 INFO - STDOUT: __________________ ERROR at setup of test_null_response_value __________________
[task 2019-10-17T04:28:15.036Z] 04:28:15 INFO - STDOUT: capabilities = {}
[task 2019-10-17T04:28:15.038Z] 04:28:15 INFO - STDOUT: configuration = {'capabilities': {'moz:firefoxOptions': {'binary': '/builds/worker/workspace/build/application/firefox/firefox', 'pref...platform.test,www2.www.not-web-platform.test', 'network.process.enabled': False}}}, 'host': '127.0.0.1', 'port': 43984}
[task 2019-10-17T04:28:15.040Z] 04:28:15 INFO - STDOUT: request = <SubRequest 'session' for <Function 'test_null_response_value'>>
[task 2019-10-17T04:28:15.041Z] 04:28:15 INFO - STDOUT: @pytest.fixture(scope="function")
[task 2019-10-17T04:28:15.043Z] 04:28:15 INFO - STDOUT: def session(capabilities, configuration, request):
[task 2019-10-17T04:28:15.044Z] 04:28:15 INFO - STDOUT: """Create and start a session for a test that does not itself test session creation.
[task 2019-10-17T04:28:15.046Z] 04:28:15 INFO - STDOUT:
[task 2019-10-17T04:28:15.047Z] 04:28:15 INFO - STDOUT: By default the session will stay open after each test, but we always try to start a
[task 2019-10-17T04:28:15.049Z] 04:28:15 INFO - STDOUT: new one and assume that if that fails there is already a valid session. This makes it
[task 2019-10-17T04:28:15.052Z] 04:28:15 INFO - STDOUT: possible to recover from some errors that might leave the session in a bad state, but
[task 2019-10-17T04:28:15.052Z] 04:28:15 INFO - STDOUT: does not demand that we start a new session per test."""
[task 2019-10-17T04:28:15.053Z] 04:28:15 INFO - STDOUT: global _current_session
[task 2019-10-17T04:28:15.053Z] 04:28:15 INFO - STDOUT:
[task 2019-10-17T04:28:15.054Z] 04:28:15 INFO - STDOUT: # Update configuration capabilities with custom ones from the
[task 2019-10-17T04:28:15.054Z] 04:28:15 INFO - STDOUT: # capabilities fixture, which can be set by tests
[task 2019-10-17T04:28:15.055Z] 04:28:15 INFO - STDOUT: caps = copy.deepcopy(configuration["capabilities"])
[task 2019-10-17T04:28:15.055Z] 04:28:15 INFO - STDOUT: caps.update(capabilities)
[task 2019-10-17T04:28:15.056Z] 04:28:15 INFO - STDOUT: caps = {"alwaysMatch": caps}
[task 2019-10-17T04:28:15.057Z] 04:28:15 INFO - STDOUT:
[task 2019-10-17T04:28:15.060Z] 04:28:15 INFO - STDOUT: # If there is a session with different capabilities active, end it now
[task 2019-10-17T04:28:15.061Z] 04:28:15 INFO - STDOUT: if _current_session is not None and (
[task 2019-10-17T04:28:15.062Z] 04:28:15 INFO - STDOUT: caps != _current_session.requested_capabilities):
[task 2019-10-17T04:28:15.062Z] 04:28:15 INFO - STDOUT: _current_session.end()
[task 2019-10-17T04:28:15.064Z] 04:28:15 INFO - STDOUT: _current_session = None
[task 2019-10-17T04:28:15.064Z] 04:28:15 INFO - STDOUT:
[task 2019-10-17T04:28:15.066Z] 04:28:15 INFO - STDOUT: if _current_session is None:
[task 2019-10-17T04:28:15.066Z] 04:28:15 INFO - STDOUT: _current_session = webdriver.Session(
[task 2019-10-17T04:28:15.068Z] 04:28:15 INFO - STDOUT: configuration["host"],
[task 2019-10-17T04:28:15.072Z] 04:28:15 INFO - STDOUT: configuration["port"],
[task 2019-10-17T04:28:15.073Z] 04:28:15 INFO - STDOUT: capabilities=caps)
[task 2019-10-17T04:28:15.075Z] 04:28:15 INFO - STDOUT: try:
[task 2019-10-17T04:28:15.076Z] 04:28:15 INFO - STDOUT: > _current_session.start()
[task 2019-10-17T04:28:15.077Z] 04:28:15 INFO - STDOUT: capabilities = {}
[task 2019-10-17T04:28:15.078Z] 04:28:15 INFO - STDOUT: caps = {'alwaysMatch': {'moz:firefoxOptions': {'binary': '/builds/worker/workspace/build/application/firefox/firefox', 'prefs...form.test,xn--lve-6lad.www2.not-web-platform.test,www2.www.not-web-platform.test', 'network.process.enabled': False}}}}
[task 2019-10-17T04:28:15.080Z] 04:28:15 INFO - STDOUT: configuration = {'capabilities': {'moz:firefoxOptions': {'binary': '/builds/worker/workspace/build/application/firefox/firefox', 'pref...platform.test,www2.www.not-web-platform.test', 'network.process.enabled': False}}}, 'host': '127.0.0.1', 'port': 43984}
[task 2019-10-17T04:28:15.081Z] 04:28:15 INFO - STDOUT: request = <SubRequest 'session' for <Function 'test_null_response_value'>>
[task 2019-10-17T04:28:15.082Z] 04:28:15 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/support/fixtures.py
[task 2019-10-17T04:28:15.083Z] 04:28:15 INFO - STDOUT: :150:
[task 2019-10-17T04:28:15.084Z] 04:28:15 INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2019-10-17T04:28:15.085Z] 04:28:15 INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/client.py
[task 2019-10-17T04:28:15.087Z] 04:28:15 INFO - STDOUT: :429: in start
[task 2019-10-17T04:28:15.088Z] 04:28:15 INFO - STDOUT: value = self.send_command("POST", "session", body=body)
[task 2019-10-17T04:28:15.089Z] 04:28:15 INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/client.py
[task 2019-10-17T04:28:15.091Z] 04:28:15 INFO - STDOUT: :471: in send_command
[task 2019-10-17T04:28:15.092Z] 04:28:15 INFO - STDOUT: session=self)
[task 2019-10-17T04:28:15.093Z] 04:28:15 INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/transport.py
[task 2019-10-17T04:28:15.095Z] 04:28:15 INFO - STDOUT: :175: in send
[task 2019-10-17T04:28:15.095Z] 04:28:15 INFO - STDOUT: response = self._request(method, uri, payload, headers)
[task 2019-10-17T04:28:15.096Z] 04:28:15 INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/transport.py
[task 2019-10-17T04:28:15.097Z] 04:28:15 INFO - STDOUT: :190: in _request
[task 2019-10-17T04:28:15.098Z] 04:28:15 INFO - STDOUT: self.connection.request(method, url, payload, headers)
[task 2019-10-17T04:28:15.098Z] 04:28:15 INFO - STDOUT: /usr/lib/python2.7/httplib.py
[task 2019-10-17T04:28:15.099Z] 04:28:15 INFO - STDOUT: :1057: in request
[task 2019-10-17T04:28:15.100Z] 04:28:15 INFO - STDOUT: self._send_request(method, url, body, headers)
[task 2019-10-17T04:28:15.101Z] 04:28:15 INFO - STDOUT: /usr/lib/python2.7/httplib.py
[task 2019-10-17T04:28:15.101Z] 04:28:15 INFO - STDOUT: :1097: in _send_request
[task 2019-10-17T04:28:15.102Z] 04:28:15 INFO - STDOUT: self.endheaders(body)
[task 2019-10-17T04:28:15.103Z] 04:28:15 INFO - STDOUT: /usr/lib/python2.7/httplib.py
[task 2019-10-17T04:28:15.104Z] 04:28:15 INFO - STDOUT: :1053: in endheaders
[task 2019-10-17T04:28:15.104Z] 04:28:15 INFO - STDOUT: self._send_output(message_body)
[task 2019-10-17T04:28:15.105Z] 04:28:15 INFO - STDOUT: /usr/lib/python2.7/httplib.py
[task 2019-10-17T04:28:15.105Z] 04:28:15 INFO - STDOUT: :897: in _send_output
[task 2019-10-17T04:28:15.106Z] 04:28:15 INFO - STDOUT: self.send(msg)
[task 2019-10-17T04:28:15.106Z] 04:28:15 INFO - STDOUT: /usr/lib/python2.7/httplib.py
[task 2019-10-17T04:28:15.106Z] 04:28:15 INFO - STDOUT: :859: in send
[task 2019-10-17T04:28:15.107Z] 04:28:15 INFO - STDOUT: self.connect()
[task 2019-10-17T04:28:15.107Z] 04:28:15 INFO - STDOUT: /usr/lib/python2.7/httplib.py
[task 2019-10-17T04:28:15.107Z] 04:28:15 INFO - STDOUT: :836: in connect
[task 2019-10-17T04:28:15.108Z] 04:28:15 INFO - STDOUT: self.timeout, self.source_address)
[task 2019-10-17T04:28:15.108Z] 04:28:15 INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2019-10-17T04:28:15.109Z] 04:28:15 INFO - STDOUT: address = ('127.0.0.1', 43984), timeout = <object object at 0x7f8f185c60d0>
[task 2019-10-17T04:28:15.109Z] 04:28:15 INFO - STDOUT: source_address = None
[task 2019-10-17T04:28:15.109Z] 04:28:15 INFO - STDOUT: def create_connection(address, timeout=_GLOBAL_DEFAULT_TIMEOUT,
[task 2019-10-17T04:28:15.111Z] 04:28:15 INFO - STDOUT: source_address=None):
[task 2019-10-17T04:28:15.111Z] 04:28:15 INFO - STDOUT: """Connect to address and return the socket object.
[task 2019-10-17T04:28:15.112Z] 04:28:15 INFO - STDOUT:
[task 2019-10-17T04:28:15.112Z] 04:28:15 INFO - STDOUT: Convenience function. Connect to address (a 2-tuple (host, [task 2019-10-17T04:28:15.112Z] 04:28:15 INFO - STDOUT: port)) and return the socket object. Passing the optional
[task 2019-10-17T04:28:15.113Z] 04:28:15 INFO - STDOUT: timeout parameter will set the timeout on the socket instance
[task 2019-10-17T04:28:15.114Z] 04:28:15 INFO - STDOUT: before attempting to connect. If no timeout is supplied, the
[task 2019-10-17T04:28:15.114Z] 04:28:15 INFO - STDOUT: global default timeout setting returned by :func:getdefaulttimeout
[task 2019-10-17T04:28:15.116Z] 04:28:15 INFO - STDOUT: is used. If source_address is set it must be a tuple of (host, port)
[task 2019-10-17T04:28:15.116Z] 04:28:15 INFO - STDOUT: for the socket to bind as a source address before making the connection.
[task 2019-10-17T04:28:15.116Z] 04:28:15 INFO - STDOUT: A host of '' or port 0 tells the OS to use the default.
[task 2019-10-17T04:28:15.117Z] 04:28:15 INFO - STDOUT: """
[task 2019-10-17T04:28:15.117Z] 04:28:15 INFO - STDOUT:
[task 2019-10-17T04:28:15.119Z] 04:28:15 INFO - STDOUT: host, port = address
[task 2019-10-17T04:28:15.119Z] 04:28:15 INFO - STDOUT: err = None
[task 2019-10-17T04:28:15.121Z] 04:28:15 INFO - STDOUT: for res in getaddrinfo(host, port, 0, SOCK_STREAM):
[task 2019-10-17T04:28:15.121Z] 04:28:15 INFO - STDOUT: af, socktype, proto, canonname, sa = res
[task 2019-10-17T04:28:15.122Z] 04:28:15 INFO - STDOUT: sock = None
[task 2019-10-17T04:28:15.123Z] 04:28:15 INFO - STDOUT: try:
[task 2019-10-17T04:28:15.123Z] 04:28:15 INFO - STDOUT: sock = socket(af, socktype, proto)
[task 2019-10-17T04:28:15.125Z] 04:28:15 INFO - STDOUT: if timeout is not _GLOBAL_DEFAULT_TIMEOUT:
[task 2019-10-17T04:28:15.125Z] 04:28:15 INFO - STDOUT: sock.settimeout(timeout)
[task 2019-10-17T04:28:15.126Z] 04:28:15 INFO - STDOUT: if source_address:
[task 2019-10-17T04:28:15.126Z] 04:28:15 INFO - STDOUT: sock.bind(source_address)
[task 2019-10-17T04:28:15.127Z] 04:28:15 INFO - STDOUT: sock.connect(sa)
[task 2019-10-17T04:28:15.128Z] 04:28:15 INFO - STDOUT: return sock
[task 2019-10-17T04:28:15.129Z] 04:28:15 INFO - STDOUT:
[task 2019-10-17T04:28:15.130Z] 04:28:15 INFO - STDOUT: except error as _:
[task 2019-10-17T04:28:15.130Z] 04:28:15 INFO - STDOUT: err = _
[task 2019-10-17T04:28:15.131Z] 04:28:15 INFO - STDOUT: if sock is not None:
[task 2019-10-17T04:28:15.131Z] 04:28:15 INFO - STDOUT: sock.close()
[task 2019-10-17T04:28:15.131Z] 04:28:15 INFO - STDOUT:
[task 2019-10-17T04:28:15.133Z] 04:28:15 INFO - STDOUT: if err is not None:
[task 2019-10-17T04:28:15.134Z] 04:28:15 INFO - STDOUT: > raise err
[task 2019-10-17T04:28:15.134Z] 04:28:15 INFO - STDOUT: E error: [Errno 111] Connection refused
[task 2019-10-17T04:28:15.135Z] 04:28:15 INFO - STDOUT: _ = error(111, 'Connection refused')
[task 2019-10-17T04:28:15.136Z] 04:28:15 INFO - STDOUT: address = ('127.0.0.1', 43984)
[task 2019-10-17T04:28:15.137Z] 04:28:15 INFO - STDOUT: af = 2
[task 2019-10-17T04:28:15.137Z] 04:28:15 INFO - STDOUT: canonname = ''
[task 2019-10-17T04:28:15.138Z] 04:28:15 INFO - STDOUT: err = error(111, 'Connection refused')
[task 2019-10-17T04:28:15.138Z] 04:28:15 INFO - STDOUT: host = '127.0.0.1'
[task 2019-10-17T04:28:15.139Z] 04:28:15 INFO - STDOUT: port = 43984
[task 2019-10-17T04:28:15.141Z] 04:28:15 INFO - STDOUT: proto = 6
[task 2019-10-17T04:28:15.141Z] 04:28:15 INFO - STDOUT: res = (2, 1, 6, '', ('127.0.0.1', 43984))
[task 2019-10-17T04:28:15.142Z] 04:28:15 INFO - STDOUT: sa = ('127.0.0.1', 43984)
[task 2019-10-17T04:28:15.142Z] 04:28:15 INFO - STDOUT: sock = <socket._socketobject object at 0x7f8f0ee286e0>
[task 2019-10-17T04:28:15.143Z] 04:28:15 INFO - STDOUT: socktype = 1
[task 2019-10-17T04:28:15.144Z] 04:28:15 INFO - STDOUT: source_address = None
[task 2019-10-17T04:28:15.145Z] 04:28:15 INFO - STDOUT: timeout = <object object at 0x7f8f185c60d0>
[task 2019-10-17T04:28:15.145Z] 04:28:15 INFO - STDOUT: /usr/lib/python2.7/socket.py
[task 2019-10-17T04:28:15.146Z] 04:28:15 INFO - STDOUT: :575: error
[task 2019-10-17T04:28:15.146Z] 04:28:15 INFO - STDOUT: __________________ ERROR at setup of test_no_browsing_context __________________
...
[task 2019-10-17T04:28:15.272Z] 04:28:15 INFO - STDOUT: self = <httplib.HTTPConnection instance at 0x7f8f0d3e3710>, method = 'POST'
[task 2019-10-17T04:28:15.273Z] 04:28:15 INFO - STDOUT: url = '/session', skip_host = 0, skip_accept_encoding = 0
[task 2019-10-17T04:28:15.274Z] 04:28:15 INFO - STDOUT: def putrequest(self, method, url, skip_host=0, skip_accept_encoding=0):
[task 2019-10-17T04:28:15.275Z] 04:28:15 INFO - STDOUT: """Send a request to the server.
[task 2019-10-17T04:28:15.275Z] 04:28:15 INFO - STDOUT:
[task 2019-10-17T04:28:15.275Z] 04:28:15 INFO - STDOUT: method' specifies an HTTP request method, e.g. 'GET'. [task 2019-10-17T04:28:15.276Z] 04:28:15 INFO - STDOUT: url' specifies the object being requested, e.g. '/index.html'.
[task 2019-10-17T04:28:15.277Z] 04:28:15 INFO - STDOUT: skip_host' if True does not add automatically a 'Host:' header [task 2019-10-17T04:28:15.278Z] 04:28:15 INFO - STDOUT: skip_accept_encoding' if True does not add automatically an
[task 2019-10-17T04:28:15.279Z] 04:28:15 INFO - STDOUT: 'Accept-Encoding:' header
[task 2019-10-17T04:28:15.279Z] 04:28:15 INFO - STDOUT: """
[task 2019-10-17T04:28:15.279Z] 04:28:15 INFO - STDOUT:
[task 2019-10-17T04:28:15.281Z] 04:28:15 INFO - STDOUT: # if a prior response has been completed, then forget about it.
[task 2019-10-17T04:28:15.281Z] 04:28:15 INFO - STDOUT: if self.__response and self.__response.isclosed():
[task 2019-10-17T04:28:15.282Z] 04:28:15 INFO - STDOUT: self.__response = None
[task 2019-10-17T04:28:15.282Z] 04:28:15 INFO - STDOUT:
[task 2019-10-17T04:28:15.283Z] 04:28:15 INFO - STDOUT:
[task 2019-10-17T04:28:15.284Z] 04:28:15 INFO - STDOUT: # in certain cases, we cannot issue another request on this connection.
[task 2019-10-17T04:28:15.285Z] 04:28:15 INFO - STDOUT: # this occurs when:
[task 2019-10-17T04:28:15.285Z] 04:28:15 INFO - STDOUT: # 1) we are in the process of sending a request. (_CS_REQ_STARTED)
[task 2019-10-17T04:28:15.285Z] 04:28:15 INFO - STDOUT: # 2) a response to a previous request has signalled that it is going
[task 2019-10-17T04:28:15.287Z] 04:28:15 INFO - STDOUT: # to close the connection upon completion.
[task 2019-10-17T04:28:15.287Z] 04:28:15 INFO - STDOUT: # 3) the headers for the previous response have not been read, thus
[task 2019-10-17T04:28:15.288Z] 04:28:15 INFO - STDOUT: # we cannot determine whether point (2) is true. (_CS_REQ_SENT)
[task 2019-10-17T04:28:15.288Z] 04:28:15 INFO - STDOUT: #
[task 2019-10-17T04:28:15.290Z] 04:28:15 INFO - STDOUT: # if there is no prior response, then we can request at will.
[task 2019-10-17T04:28:15.290Z] 04:28:15 INFO - STDOUT: #
[task 2019-10-17T04:28:15.290Z] 04:28:15 INFO - STDOUT: # if point (2) is true, then we will have passed the socket to the
[task 2019-10-17T04:28:15.291Z] 04:28:15 INFO - STDOUT: # response (effectively meaning, "there is no prior response"), and
[task 2019-10-17T04:28:15.291Z] 04:28:15 INFO - STDOUT: # will open a new one when a new request is made.
[task 2019-10-17T04:28:15.293Z] 04:28:15 INFO - STDOUT: #
[task 2019-10-17T04:28:15.293Z] 04:28:15 INFO - STDOUT: # Note: if a prior response exists, then we can start a new request.
[task 2019-10-17T04:28:15.294Z] 04:28:15 INFO - STDOUT: # We are not allowed to begin fetching the response to this new
[task 2019-10-17T04:28:15.295Z] 04:28:15 INFO - STDOUT: # request, however, until that prior response is complete.
[task 2019-10-17T04:28:15.296Z] 04:28:15 INFO - STDOUT: #
[task 2019-10-17T04:28:15.296Z] 04:28:15 INFO - STDOUT: if self.__state == _CS_IDLE:
[task 2019-10-17T04:28:15.296Z] 04:28:15 INFO - STDOUT: self.__state = _CS_REQ_STARTED
[task 2019-10-17T04:28:15.298Z] 04:28:15 INFO - STDOUT: else:
[task 2019-10-17T04:28:15.298Z] 04:28:15 INFO - STDOUT: > raise CannotSendRequest()
[task 2019-10-17T04:28:15.299Z] 04:28:15 INFO - STDOUT: E CannotSendRequest
[task 2019-10-17T04:28:15.299Z] 04:28:15 INFO - STDOUT: method = 'POST'
[task 2019-10-17T04:28:15.300Z] 04:28:15 INFO - STDOUT: self = <httplib.HTTPConnection instance at 0x7f8f0d3e3710>
[task 2019-10-17T04:28:15.301Z] 04:28:15 INFO - STDOUT: skip_accept_encoding = 0
[task 2019-10-17T04:28:15.302Z] 04:28:15 INFO - STDOUT: skip_host = 0
[task 2019-10-17T04:28:15.303Z] 04:28:15 INFO - STDOUT: url = '/session'
[task 2019-10-17T04:28:15.303Z] 04:28:15 INFO - STDOUT: /usr/lib/python2.7/httplib.py
[task 2019-10-17T04:28:15.303Z] 04:28:15 INFO - STDOUT: :939: CannotSendRequest
[task 2019-10-17T04:28:15.305Z] 04:28:15 INFO - STDOUT: =============================== warnings summary ===============================
[task 2019-10-17T04:28:15.305Z] 04:28:15 INFO - STDOUT: <undetermined location>
[task 2019-10-17T04:28:15.306Z] 04:28:15 INFO - STDOUT: Module already imported so cannot be rewritten: mozlog
[task 2019-10-17T04:28:15.306Z] 04:28:15 INFO - STDOUT: -- Docs: http://doc.pytest.org/en/latest/warnings.html
[task 2019-10-17T04:28:15.308Z] 04:28:15 INFO - STDOUT: ===================== 1 warnings, 3 error in 0.26 seconds ======================
[task 2019-10-17T04:28:15.312Z] 04:28:15 INFO -
[task 2019-10-17T04:28:15.312Z] 04:28:15 INFO - TEST-UNEXPECTED-ERROR | /webdriver/tests/delete_cookie/delete.py | test_null_response_value - setup error
[task 2019-10-17T04:28:15.312Z] 04:28:15 INFO - capabilities = {}
[task 2019-10-17T04:28:15.312Z] 04:28:15 INFO - configuration = {'capabilities': {'moz:firefoxOptions': {'binary': '/builds/worker/workspace/build/application/firefox/firefox', 'pref...platform.test,www2.www.not-web-platform.test', 'network.process.enabled': False}}}, 'host': '127.0.0.1', 'port': 43984}
[task 2019-10-17T04:28:15.313Z] 04:28:15 INFO - request = <SubRequest 'session' for <Function 'test_null_response_value'>>
[task 2019-10-17T04:28:15.313Z] 04:28:15 INFO -
...
[task 2019-10-17T04:28:15.324Z] 04:28:15 INFO - address = ('127.0.0.1', 43984), timeout = <object object at 0x7f8f185c60d0>
[task 2019-10-17T04:28:15.324Z] 04:28:15 INFO - source_address = None
[task 2019-10-17T04:28:15.324Z] 04:28:15 INFO -
[task 2019-10-17T04:28:15.324Z] 04:28:15 INFO - def create_connection(address, timeout=_GLOBAL_DEFAULT_TIMEOUT,
[task 2019-10-17T04:28:15.324Z] 04:28:15 INFO - source_address=None):
[task 2019-10-17T04:28:15.324Z] 04:28:15 INFO - """Connect to address and return the socket object.
[task 2019-10-17T04:28:15.324Z] 04:28:15 INFO -
[task 2019-10-17T04:28:15.324Z] 04:28:15 INFO - Convenience function. Connect to address (a 2-tuple (host, [task 2019-10-17T04:28:15.324Z] 04:28:15 INFO - port)) and return the socket object. Passing the optional
[task 2019-10-17T04:28:15.325Z] 04:28:15 INFO - timeout parameter will set the timeout on the socket instance
[task 2019-10-17T04:28:15.325Z] 04:28:15 INFO - before attempting to connect. If no timeout is supplied, the
[task 2019-10-17T04:28:15.325Z] 04:28:15 INFO - global default timeout setting returned by :func:getdefaulttimeout
[task 2019-10-17T04:28:15.325Z] 04:28:15 INFO - is used. If source_address is set it must be a tuple of (host, port)
[task 2019-10-17T04:28:15.325Z] 04:28:15 INFO - for the socket to bind as a source address before making the connection.
[task 2019-10-17T04:28:15.325Z] 04:28:15 INFO - A host of '' or port 0 tells the OS to use the default.
[task 2019-10-17T04:28:15.325Z] 04:28:15 INFO - """
[task 2019-10-17T04:28:15.325Z] 04:28:15 INFO -
[task 2019-10-17T04:28:15.325Z] 04:28:15 INFO - host, port = address
[task 2019-10-17T04:28:15.325Z] 04:28:15 INFO - err = None
[task 2019-10-17T04:28:15.326Z] 04:28:15 INFO - for res in getaddrinfo(host, port, 0, SOCK_STREAM):
[task 2019-10-17T04:28:15.326Z] 04:28:15 INFO - af, socktype, proto, canonname, sa = res
[task 2019-10-17T04:28:15.326Z] 04:28:15 INFO - sock = None
[task 2019-10-17T04:28:15.326Z] 04:28:15 INFO - try:
[task 2019-10-17T04:28:15.326Z] 04:28:15 INFO - sock = socket(af, socktype, proto)
[task 2019-10-17T04:28:15.326Z] 04:28:15 INFO - if timeout is not _GLOBAL_DEFAULT_TIMEOUT:
[task 2019-10-17T04:28:15.326Z] 04:28:15 INFO - sock.settimeout(timeout)
[task 2019-10-17T04:28:15.326Z] 04:28:15 INFO - if source_address:
[task 2019-10-17T04:28:15.326Z] 04:28:15 INFO - sock.bind(source_address)
[task 2019-10-17T04:28:15.326Z] 04:28:15 INFO - sock.connect(sa)
[task 2019-10-17T04:28:15.326Z] 04:28:15 INFO - return sock
[task 2019-10-17T04:28:15.326Z] 04:28:15 INFO -
[task 2019-10-17T04:28:15.327Z] 04:28:15 INFO - except error as _:
[task 2019-10-17T04:28:15.327Z] 04:28:15 INFO - err = _
[task 2019-10-17T04:28:15.327Z] 04:28:15 INFO - if sock is not None:
[task 2019-10-17T04:28:15.327Z] 04:28:15 INFO - sock.close()
[task 2019-10-17T04:28:15.327Z] 04:28:15 INFO -
[task 2019-10-17T04:28:15.327Z] 04:28:15 INFO - if err is not None:
[task 2019-10-17T04:28:15.327Z] 04:28:15 INFO - > raise err
[task 2019-10-17T04:28:15.327Z] 04:28:15 INFO - E error: [Errno 111] Connection refused
[task 2019-10-17T04:28:15.327Z] 04:28:15 INFO -
[task 2019-10-17T04:28:15.327Z] 04:28:15 INFO - _ = error(111, 'Connection refused')
[task 2019-10-17T04:28:15.327Z] 04:28:15 INFO - address = ('127.0.0.1', 43984)
[task 2019-10-17T04:28:15.328Z] 04:28:15 INFO - af = 2
[task 2019-10-17T04:28:15.328Z] 04:28:15 INFO - canonname = ''
[task 2019-10-17T04:28:15.328Z] 04:28:15 INFO - err = error(111, 'Connection refused')
[task 2019-10-17T04:28:15.328Z] 04:28:15 INFO - host = '127.0.0.1'
[task 2019-10-17T04:28:15.328Z] 04:28:15 INFO - port = 43984
[task 2019-10-17T04:28:15.328Z] 04:28:15 INFO - proto = 6
[task 2019-10-17T04:28:15.328Z] 04:28:15 INFO - res = (2, 1, 6, '', ('127.0.0.1', 43984))
[task 2019-10-17T04:28:15.328Z] 04:28:15 INFO - sa = ('127.0.0.1', 43984)
[task 2019-10-17T04:28:15.328Z] 04:28:15 INFO - sock = <socket._socketobject object at 0x7f8f0ee286e0>
[task 2019-10-17T04:28:15.329Z] 04:28:15 INFO - socktype = 1
[task 2019-10-17T04:28:15.329Z] 04:28:15 INFO - source_address = None
[task 2019-10-17T04:28:15.329Z] 04:28:15 INFO - timeout = <object object at 0x7f8f185c60d0>
[task 2019-10-17T04:28:15.329Z] 04:28:15 INFO -
[task 2019-10-17T04:28:15.329Z] 04:28:15 INFO - /usr/lib/python2.7/socket.py:575: error
[task 2019-10-17T04:28:15.330Z] 04:28:15 INFO -
[task 2019-10-17T04:28:15.330Z] 04:28:15 INFO - TEST-UNEXPECTED-ERROR | /webdriver/tests/delete_cookie/delete.py | test_no_browsing_context - setup error
[task 2019-10-17T04:28:15.330Z] 04:28:15 INFO - capabilities = {}
[task 2019-10-17T04:28:15.331Z] 04:28:15 INFO - configuration = {'capabilities': {'moz:firefoxOptions': {'binary': '/builds/worker/workspace/build/application/firefox/firefox', 'pref...platform.test,www2.www.not-web-platform.test', 'network.process.enabled': False}}}, 'host': '127.0.0.1', 'port': 43984}
[task 2019-10-17T04:28:15.331Z] 04:28:15 INFO - request = <SubRequest 'session' for <Function 'test_no_browsing_context'>>
[task 2019-10-17T04:28:15.331Z] 04:28:15 INFO -
[task 2019-10-17T04:28:15.331Z] 04:28:15 INFO - @pytest.fixture(scope="function")
[task 2019-10-17T04:28:15.331Z] 04:28:15 INFO - def session(capabilities, configuration, request):
[task 2019-10-17T04:28:15.331Z] 04:28:15 INFO - """Create and start a session for a test that does not itself test session creation.
[task 2019-10-17T04:28:15.332Z] 04:28:15 INFO -
[task 2019-10-17T04:28:15.332Z] 04:28:15 INFO - By default the session will stay open after each test, but we always try to start a
[task 2019-10-17T04:28:15.332Z] 04:28:15 INFO - new one and assume that if that fails there is already a valid session. This makes it
[task 2019-10-17T04:28:15.332Z] 04:28:15 INFO - possible to recover from some errors that might leave the session in a bad state, but
[task 2019-10-17T04:28:15.332Z] 04:28:15 INFO - does not demand that we start a new session per test."""
[task 2019-10-17T04:28:15.332Z] 04:28:15 INFO - global _current_session
[task 2019-10-17T04:28:15.333Z] 04:28:15 INFO -
...
[task 2019-10-17T04:28:15.340Z] 04:28:15 INFO - self = <httplib.HTTPConnection instance at 0x7f8f0d3e3710>, method = 'POST'
[task 2019-10-17T04:28:15.340Z] 04:28:15 INFO - url = '/session', skip_host = 0, skip_accept_encoding = 0
[task 2019-10-17T04:28:15.340Z] 04:28:15 INFO -
[task 2019-10-17T04:28:15.340Z] 04:28:15 INFO - def putrequest(self, method, url, skip_host=0, skip_accept_encoding=0):
[task 2019-10-17T04:28:15.340Z] 04:28:15 INFO - """Send a request to the server.
[task 2019-10-17T04:28:15.340Z] 04:28:15 INFO -
[task 2019-10-17T04:28:15.340Z] 04:28:15 INFO - method' specifies an HTTP request method, e.g. 'GET'. [task 2019-10-17T04:28:15.340Z] 04:28:15 INFO - url' specifies the object being requested, e.g. '/index.html'.
[task 2019-10-17T04:28:15.340Z] 04:28:15 INFO - skip_host' if True does not add automatically a 'Host:' header [task 2019-10-17T04:28:15.340Z] 04:28:15 INFO - skip_accept_encoding' if True does not add automatically an
[task 2019-10-17T04:28:15.340Z] 04:28:15 INFO - 'Accept-Encoding:' header
[task 2019-10-17T04:28:15.340Z] 04:28:15 INFO - """
[task 2019-10-17T04:28:15.341Z] 04:28:15 INFO -
[task 2019-10-17T04:28:15.341Z] 04:28:15 INFO - # if a prior response has been completed, then forget about it.
[task 2019-10-17T04:28:15.341Z] 04:28:15 INFO - if self.__response and self.__response.isclosed():
[task 2019-10-17T04:28:15.341Z] 04:28:15 INFO - self.__response = None
[task 2019-10-17T04:28:15.341Z] 04:28:15 INFO -
[task 2019-10-17T04:28:15.341Z] 04:28:15 INFO -
[task 2019-10-17T04:28:15.341Z] 04:28:15 INFO - # in certain cases, we cannot issue another request on this connection.
[task 2019-10-17T04:28:15.341Z] 04:28:15 INFO - # this occurs when:
[task 2019-10-17T04:28:15.341Z] 04:28:15 INFO - # 1) we are in the process of sending a request. (_CS_REQ_STARTED)
[task 2019-10-17T04:28:15.341Z] 04:28:15 INFO - # 2) a response to a previous request has signalled that it is going
[task 2019-10-17T04:28:15.341Z] 04:28:15 INFO - # to close the connection upon completion.
[task 2019-10-17T04:28:15.342Z] 04:28:15 INFO - # 3) the headers for the previous response have not been read, thus
[task 2019-10-17T04:28:15.342Z] 04:28:15 INFO - # we cannot determine whether point (2) is true. (_CS_REQ_SENT)
[task 2019-10-17T04:28:15.342Z] 04:28:15 INFO - #
[task 2019-10-17T04:28:15.342Z] 04:28:15 INFO - # if there is no prior response, then we can request at will.
[task 2019-10-17T04:28:15.342Z] 04:28:15 INFO - #
[task 2019-10-17T04:28:15.342Z] 04:28:15 INFO - # if point (2) is true, then we will have passed the socket to the
[task 2019-10-17T04:28:15.342Z] 04:28:15 INFO - # response (effectively meaning, "there is no prior response"), and
[task 2019-10-17T04:28:15.342Z] 04:28:15 INFO - # will open a new one when a new request is made.
[task 2019-10-17T04:28:15.343Z] 04:28:15 INFO - #
[task 2019-10-17T04:28:15.343Z] 04:28:15 INFO - # Note: if a prior response exists, then we can start a new request.
[task 2019-10-17T04:28:15.343Z] 04:28:15 INFO - # We are not allowed to begin fetching the response to this new
[task 2019-10-17T04:28:15.343Z] 04:28:15 INFO - # request, however, until that prior response is complete.
[task 2019-10-17T04:28:15.343Z] 04:28:15 INFO - #
[task 2019-10-17T04:28:15.343Z] 04:28:15 INFO - if self.__state == _CS_IDLE:
[task 2019-10-17T04:28:15.343Z] 04:28:15 INFO - self.__state = _CS_REQ_STARTED
[task 2019-10-17T04:28:15.343Z] 04:28:15 INFO - else:
[task 2019-10-17T04:28:15.344Z] 04:28:15 INFO - > raise CannotSendRequest()
[task 2019-10-17T04:28:15.344Z] 04:28:15 INFO - E CannotSendRequest
[task 2019-10-17T04:28:15.344Z] 04:28:15 INFO -
[task 2019-10-17T04:28:15.344Z] 04:28:15 INFO - method = 'POST'
[task 2019-10-17T04:28:15.344Z] 04:28:15 INFO - self = <httplib.HTTPConnection instance at 0x7f8f0d3e3710>
[task 2019-10-17T04:28:15.345Z] 04:28:15 INFO - skip_accept_encoding = 0
[task 2019-10-17T04:28:15.345Z] 04:28:15 INFO - skip_host = 0
[task 2019-10-17T04:28:15.345Z] 04:28:15 INFO - url = '/session'
[task 2019-10-17T04:28:15.345Z] 04:28:15 INFO -
[task 2019-10-17T04:28:15.345Z] 04:28:15 INFO - /usr/lib/python2.7/httplib.py:939: CannotSendRequest
[task 2019-10-17T04:28:15.346Z] 04:28:15 INFO -
[task 2019-10-17T04:28:15.346Z] 04:28:15 INFO - TEST-UNEXPECTED-ERROR | /webdriver/tests/delete_cookie/delete.py | test_unknown_cookie - setup error
[task 2019-10-17T04:28:15.347Z] 04:28:15 INFO - capabilities = {}
[task 2019-10-17T04:28:15.347Z] 04:28:15 INFO - configuration = {'capabilities': {'moz:firefoxOptions': {'binary': '/builds/worker/workspace/build/application/firefox/firefox', 'pref...platform.test,www2.www.not-web-platform.test', 'network.process.enabled': False}}}, 'host': '127.0.0.1', 'port': 43984}
[task 2019-10-17T04:28:15.347Z] 04:28:15 INFO - request = <SubRequest 'session' for <Function 'test_unknown_cookie'>>
[task 2019-10-17T04:28:15.347Z] 04:28:15 INFO -
[task 2019-10-17T04:28:15.347Z] 04:28:15 INFO - @pytest.fixture(scope="function")
[task 2019-10-17T04:28:15.347Z] 04:28:15 INFO - def session(capabilities, configuration, request):
[task 2019-10-17T04:28:15.347Z] 04:28:15 INFO - """Create and start a session for a test that does not itself test session creation.
[task 2019-10-17T04:28:15.348Z] 04:28:15 INFO -
[task 2019-10-17T04:28:15.348Z] 04:28:15 INFO - By default the session will stay open after each test, but we always try to start a
[task 2019-10-17T04:28:15.348Z] 04:28:15 INFO - new one and assume that if that fails there is already a valid session. This makes it
[task 2019-10-17T04:28:15.348Z] 04:28:15 INFO - possible to recover from some errors that might leave the session in a bad state, but
[task 2019-10-17T04:28:15.349Z] 04:28:15 INFO - does not demand that we start a new session per test."""
[task 2019-10-17T04:28:15.349Z] 04:28:15 INFO - global _current_session
[task 2019-10-17T04:28:15.349Z] 04:28:15 INFO -
[task 2019-10-17T04:28:15.349Z] 04:28:15 INFO - # Update configuration capabilities with custom ones from the
[task 2019-10-17T04:28:15.349Z] 04:28:15 INFO - # capabilities fixture, which can be set by tests
[task 2019-10-17T04:28:15.349Z] 04:28:15 INFO - caps = copy.deepcopy(configuration["capabilities"])
[task 2019-10-17T04:28:15.349Z] 04:28:15 INFO - caps.update(capabilities)
[task 2019-10-17T04:28:15.349Z] 04:28:15 INFO - caps = {"alwaysMatch": caps}
[task 2019-10-17T04:28:15.350Z] 04:28:15 INFO -
...

Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → DUPLICATE
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.