Closed Bug 1628591 Opened 6 years ago Closed 6 years ago

Intermittent mozilla/tests/webdriver/take_full_screenshot/iframe.py | test_source_origin[same_origin] - setup error

Categories

(Testing :: General, defect, P5)

Version 3
defect

Tracking

(Not tracked)

RESOLVED DUPLICATE of bug 1600840

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=296901604&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/IWoP3db9SoSDy5iFWrvf1g/runs/0/artifacts/public/logs/live_backing.log


...
[task 2020-04-09T06:23:25.918Z] 06:23:25     INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/client.py
[task 2020-04-09T06:23:25.919Z] 06:23:25     INFO - STDOUT: :426: in start
[task 2020-04-09T06:23:25.919Z] 06:23:25     INFO - STDOUT:     value = self.send_command("POST", "session", body=body)
[task 2020-04-09T06:23:25.921Z] 06:23:25     INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/client.py
[task 2020-04-09T06:23:25.921Z] 06:23:25     INFO - STDOUT: :468: in send_command
[task 2020-04-09T06:23:25.921Z] 06:23:25     INFO - STDOUT:     session=self, timeout=timeout)
[task 2020-04-09T06:23:25.922Z] 06:23:25     INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/transport.py
[task 2020-04-09T06:23:25.922Z] 06:23:25     INFO - STDOUT: :182: in send
[task 2020-04-09T06:23:25.922Z] 06:23:25     INFO - STDOUT:     response = self._request(method, uri, payload, headers, timeout=None)
[task 2020-04-09T06:23:25.924Z] 06:23:25     INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/transport.py
[task 2020-04-09T06:23:25.924Z] 06:23:25     INFO - STDOUT: :199: in _request
[task 2020-04-09T06:23:25.924Z] 06:23:25     INFO - STDOUT:     self.connection.request(method, url, payload, headers)
[task 2020-04-09T06:23:25.924Z] 06:23:25     INFO - STDOUT: /usr/lib/python2.7/httplib.py
[task 2020-04-09T06:23:25.925Z] 06:23:25     INFO - STDOUT: :1069: in request
[task 2020-04-09T06:23:25.925Z] 06:23:25     INFO - STDOUT:     self._send_request(method, url, body, headers)
[task 2020-04-09T06:23:25.926Z] 06:23:25     INFO - STDOUT: /usr/lib/python2.7/httplib.py
[task 2020-04-09T06:23:25.927Z] 06:23:25     INFO - STDOUT: :1109: in _send_request
[task 2020-04-09T06:23:25.927Z] 06:23:25     INFO - STDOUT:     self.endheaders(body)
[task 2020-04-09T06:23:25.928Z] 06:23:25     INFO - STDOUT: /usr/lib/python2.7/httplib.py
[task 2020-04-09T06:23:25.928Z] 06:23:25     INFO - STDOUT: :1065: in endheaders
[task 2020-04-09T06:23:25.929Z] 06:23:25     INFO - STDOUT:     self._send_output(message_body)
[task 2020-04-09T06:23:25.930Z] 06:23:25     INFO - STDOUT: /usr/lib/python2.7/httplib.py
[task 2020-04-09T06:23:25.931Z] 06:23:25     INFO - STDOUT: :892: in _send_output
[task 2020-04-09T06:23:25.931Z] 06:23:25     INFO - STDOUT:     self.send(msg)
[task 2020-04-09T06:23:25.931Z] 06:23:25     INFO - STDOUT: /usr/lib/python2.7/httplib.py
[task 2020-04-09T06:23:25.932Z] 06:23:25     INFO - STDOUT: :854: in send
[task 2020-04-09T06:23:25.932Z] 06:23:25     INFO - STDOUT:     self.connect()
[task 2020-04-09T06:23:25.933Z] 06:23:25     INFO - STDOUT: /usr/lib/python2.7/httplib.py
[task 2020-04-09T06:23:25.933Z] 06:23:25     INFO - STDOUT: :831: in connect
[task 2020-04-09T06:23:25.934Z] 06:23:25     INFO - STDOUT:     self.timeout, self.source_address)
[task 2020-04-09T06:23:25.934Z] 06:23:25     INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2020-04-09T06:23:25.934Z] 06:23:25     INFO - STDOUT: address = ('127.0.0.1', 40506), timeout = <object object at 0x7f0b24d06a00>
[task 2020-04-09T06:23:25.936Z] 06:23:25     INFO - STDOUT: source_address = None
[task 2020-04-09T06:23:25.936Z] 06:23:25     INFO - STDOUT:     def create_connection(address, timeout=_GLOBAL_DEFAULT_TIMEOUT,
[task 2020-04-09T06:23:25.937Z] 06:23:25     INFO - STDOUT:                           source_address=None):
[task 2020-04-09T06:23:25.937Z] 06:23:25     INFO - STDOUT:         """Connect to *address* and return the socket object.
[task 2020-04-09T06:23:25.938Z] 06:23:25     INFO - STDOUT:     
[task 2020-04-09T06:23:25.938Z] 06:23:25     INFO - STDOUT:         Convenience function.  Connect to *address* (a 2-tuple ``(host,
[task 2020-04-09T06:23:25.939Z] 06:23:25     INFO - STDOUT:         port)``) and return the socket object.  Passing the optional
[task 2020-04-09T06:23:25.940Z] 06:23:25     INFO - STDOUT:         *timeout* parameter will set the timeout on the socket instance
[task 2020-04-09T06:23:25.940Z] 06:23:25     INFO - STDOUT:         before attempting to connect.  If no *timeout* is supplied, the
[task 2020-04-09T06:23:25.940Z] 06:23:25     INFO - STDOUT:         global default timeout setting returned by :func:`getdefaulttimeout`
[task 2020-04-09T06:23:25.941Z] 06:23:25     INFO - STDOUT:         is used.  If *source_address* is set it must be a tuple of (host, port)
[task 2020-04-09T06:23:25.942Z] 06:23:25     INFO - STDOUT:         for the socket to bind as a source address before making the connection.
[task 2020-04-09T06:23:25.942Z] 06:23:25     INFO - STDOUT:         A host of '' or port 0 tells the OS to use the default.
[task 2020-04-09T06:23:25.943Z] 06:23:25     INFO - STDOUT:         """
[task 2020-04-09T06:23:25.943Z] 06:23:25     INFO - STDOUT:     
[task 2020-04-09T06:23:25.944Z] 06:23:25     INFO - STDOUT:         host, port = address
[task 2020-04-09T06:23:25.944Z] 06:23:25     INFO - STDOUT:         err = None
[task 2020-04-09T06:23:25.945Z] 06:23:25     INFO - STDOUT:         for res in getaddrinfo(host, port, 0, SOCK_STREAM):
[task 2020-04-09T06:23:25.946Z] 06:23:25     INFO - STDOUT:             af, socktype, proto, canonname, sa = res
[task 2020-04-09T06:23:25.946Z] 06:23:25     INFO - STDOUT:             sock = None
[task 2020-04-09T06:23:25.947Z] 06:23:25     INFO - STDOUT:             try:
[task 2020-04-09T06:23:25.948Z] 06:23:25     INFO - STDOUT:                 sock = socket(af, socktype, proto)
[task 2020-04-09T06:23:25.949Z] 06:23:25     INFO - STDOUT:                 if timeout is not _GLOBAL_DEFAULT_TIMEOUT:
[task 2020-04-09T06:23:25.949Z] 06:23:25     INFO - STDOUT:                     sock.settimeout(timeout)
[task 2020-04-09T06:23:25.950Z] 06:23:25     INFO - STDOUT:                 if source_address:
[task 2020-04-09T06:23:25.950Z] 06:23:25     INFO - STDOUT:                     sock.bind(source_address)
[task 2020-04-09T06:23:25.952Z] 06:23:25     INFO - STDOUT:                 sock.connect(sa)
[task 2020-04-09T06:23:25.952Z] 06:23:25     INFO - STDOUT:                 return sock
[task 2020-04-09T06:23:25.953Z] 06:23:25     INFO - STDOUT:     
[task 2020-04-09T06:23:25.953Z] 06:23:25     INFO - STDOUT:             except error as _:
[task 2020-04-09T06:23:25.954Z] 06:23:25     INFO - STDOUT:                 err = _
[task 2020-04-09T06:23:25.954Z] 06:23:25     INFO - STDOUT:                 if sock is not None:
[task 2020-04-09T06:23:25.956Z] 06:23:25     INFO - STDOUT:                     sock.close()
[task 2020-04-09T06:23:25.956Z] 06:23:25     INFO - STDOUT:     
[task 2020-04-09T06:23:25.957Z] 06:23:25     INFO - STDOUT:         if err is not None:
[task 2020-04-09T06:23:25.957Z] 06:23:25     INFO - STDOUT: >           raise err
[task 2020-04-09T06:23:25.957Z] 06:23:25     INFO - STDOUT: E           error: [Errno 111] Connection refused
[task 2020-04-09T06:23:25.958Z] 06:23:25     INFO - STDOUT: _          = error(111, 'Connection refused')
[task 2020-04-09T06:23:25.958Z] 06:23:25     INFO - STDOUT: address    = ('127.0.0.1', 40506)
[task 2020-04-09T06:23:25.959Z] 06:23:25     INFO - STDOUT: af         = 2
[task 2020-04-09T06:23:25.960Z] 06:23:25     INFO - STDOUT: canonname  = ''
[task 2020-04-09T06:23:25.960Z] 06:23:25     INFO - STDOUT: err        = error(111, 'Connection refused')
[task 2020-04-09T06:23:25.960Z] 06:23:25     INFO - STDOUT: host       = '127.0.0.1'
[task 2020-04-09T06:23:25.961Z] 06:23:25     INFO - STDOUT: port       = 40506
[task 2020-04-09T06:23:25.962Z] 06:23:25     INFO - STDOUT: proto      = 6
[task 2020-04-09T06:23:25.963Z] 06:23:25     INFO - STDOUT: res        = (2, 1, 6, '', ('127.0.0.1', 40506))
[task 2020-04-09T06:23:25.963Z] 06:23:25     INFO - STDOUT: sa         = ('127.0.0.1', 40506)
[task 2020-04-09T06:23:25.964Z] 06:23:25     INFO - STDOUT: sock       = <socket._socketobject object at 0x7f0b1ac16980>
[task 2020-04-09T06:23:25.964Z] 06:23:25     INFO - STDOUT: socktype   = 1
[task 2020-04-09T06:23:25.964Z] 06:23:25     INFO - STDOUT: source_address = None
[task 2020-04-09T06:23:25.966Z] 06:23:25     INFO - STDOUT: timeout    = <object object at 0x7f0b24d06a00>
[task 2020-04-09T06:23:25.966Z] 06:23:25     INFO - STDOUT: /usr/lib/python2.7/socket.py
[task 2020-04-09T06:23:25.967Z] 06:23:25     INFO - STDOUT: :575: error
[task 2020-04-09T06:23:25.967Z] 06:23:25     INFO - STDOUT: =============================== warnings summary ===============================
[task 2020-04-09T06:23:25.967Z] 06:23:25     INFO - STDOUT: <undetermined location>
[task 2020-04-09T06:23:25.969Z] 06:23:25     INFO - STDOUT:   Module already imported so cannot be rewritten: mozlog
[task 2020-04-09T06:23:25.969Z] 06:23:25     INFO - STDOUT: -- Docs: http://doc.pytest.org/en/latest/warnings.html
[task 2020-04-09T06:23:25.969Z] 06:23:25     INFO - STDOUT: ===================== 1 warnings, 2 error in 0.33 seconds ======================
[task 2020-04-09T06:23:25.973Z] 06:23:25     INFO - 
[task 2020-04-09T06:23:25.973Z] 06:23:25     INFO - TEST-UNEXPECTED-ERROR | /_mozilla/webdriver/take_full_screenshot/iframe.py | test_source_origin[same_origin] - setup error
[task 2020-04-09T06:23:25.973Z] 06:23:25     INFO - capabilities = {}
[task 2020-04-09T06:23:25.974Z] 06:23:25     INFO - configuration = {'capabilities': {'moz:firefoxOptions': {'binary': '/builds/worker/workspace/build/application/firefox/firefox', 'pref...form.test', 'network.process.enabled': False, 'toolkit.asyncshutdown.log': True}}}, 'host': '127.0.0.1', 'port': 40506}
[task 2020-04-09T06:23:25.974Z] 06:23:25     INFO - request = <SubRequest 'session' for <Function 'test_source_origin[same_origin]'>>
[task 2020-04-09T06:23:25.974Z] 06:23:25     INFO - 
[task 2020-04-09T06:23:25.974Z] 06:23:25     INFO -     @pytest.fixture(scope="function")
[task 2020-04-09T06:23:25.974Z] 06:23:25     INFO -     def session(capabilities, configuration, request):
[task 2020-04-09T06:23:25.974Z] 06:23:25     INFO -         """Create and start a session for a test that does not itself test session creation.
[task 2020-04-09T06:23:25.974Z] 06:23:25     INFO -     
[task 2020-04-09T06:23:25.974Z] 06:23:25     INFO -         By default the session will stay open after each test, but we always try to start a
[task 2020-04-09T06:23:25.974Z] 06:23:25     INFO -         new one and assume that if that fails there is already a valid session. This makes it
[task 2020-04-09T06:23:25.975Z] 06:23:25     INFO -         possible to recover from some errors that might leave the session in a bad state, but
[task 2020-04-09T06:23:25.975Z] 06:23:25     INFO -         does not demand that we start a new session per test."""
[task 2020-04-09T06:23:25.975Z] 06:23:25     INFO -         global _current_session
[task 2020-04-09T06:23:25.975Z] 06:23:25     INFO -     
[task 2020-04-09T06:23:25.975Z] 06:23:25     INFO -         # Update configuration capabilities with custom ones from the
[task 2020-04-09T06:23:25.975Z] 06:23:25     INFO -         # capabilities fixture, which can be set by tests
[task 2020-04-09T06:23:25.975Z] 06:23:25     INFO -         caps = copy.deepcopy(configuration["capabilities"])
[task 2020-04-09T06:23:25.975Z] 06:23:25     INFO -         caps.update(capabilities)
[task 2020-04-09T06:23:25.975Z] 06:23:25     INFO -         caps = {"alwaysMatch": caps}
[task 2020-04-09T06:23:25.976Z] 06:23:25     INFO -     
[task 2020-04-09T06:23:25.976Z] 06:23:25     INFO -         # If there is a session with different capabilities active, end it now
[task 2020-04-09T06:23:25.976Z] 06:23:25     INFO -         if _current_session is not None and (
[task 2020-04-09T06:23:25.976Z] 06:23:25     INFO -                 caps != _current_session.requested_capabilities):
[task 2020-04-09T06:23:25.976Z] 06:23:25     INFO -             _current_session.end()
[task 2020-04-09T06:23:25.976Z] 06:23:25     INFO -             _current_session = None
[task 2020-04-09T06:23:25.977Z] 06:23:25     INFO -     
[task 2020-04-09T06:23:25.977Z] 06:23:25     INFO -         if _current_session is None:
[task 2020-04-09T06:23:25.977Z] 06:23:25     INFO -             _current_session = webdriver.Session(
[task 2020-04-09T06:23:25.977Z] 06:23:25     INFO -                 configuration["host"],
[task 2020-04-09T06:23:25.977Z] 06:23:25     INFO -                 configuration["port"],
[task 2020-04-09T06:23:25.977Z] 06:23:25     INFO -                 capabilities=caps)
[task 2020-04-09T06:23:25.977Z] 06:23:25     INFO -         try:
[task 2020-04-09T06:23:25.978Z] 06:23:25     INFO - >           _current_session.start()
[task 2020-04-09T06:23:25.978Z] 06:23:25     INFO - 
[task 2020-04-09T06:23:25.978Z] 06:23:25     INFO - capabilities = {}
[task 2020-04-09T06:23:25.978Z] 06:23:25     INFO - caps       = {'alwaysMatch': {'moz:firefoxOptions': {'binary': '/builds/worker/workspace/build/application/firefox/firefox', 'prefs....test,xn--lve-6lad.www2.not-web-platform.test', 'network.process.enabled': False, 'toolkit.asyncshutdown.log': True}}}}
[task 2020-04-09T06:23:25.978Z] 06:23:25     INFO - configuration = {'capabilities': {'moz:firefoxOptions': {'binary': '/builds/worker/workspace/build/application/firefox/firefox', 'pref...form.test', 'network.process.enabled': False, 'toolkit.asyncshutdown.log': True}}}, 'host': '127.0.0.1', 'port': 40506}
[task 2020-04-09T06:23:25.978Z] 06:23:25     INFO - request    = <SubRequest 'session' for <Function 'test_source_origin[same_origin]'>>
[task 2020-04-09T06:23:25.978Z] 06:23:25     INFO - 
[task 2020-04-09T06:23:25.978Z] 06:23:25     INFO - tests/web-platform/tests/webdriver/tests/support/fixtures.py:150: 
[task 2020-04-09T06:23:25.978Z] 06:23:25     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2020-04-09T06:23:25.978Z] 06:23:25     INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:426: in start
...
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.