Open Bug 1756294 Opened 3 years ago Updated 6 days ago

Intermittent <random test> | Traceback (most recent call last): | After OSError: No data received over socket

Categories

(Testing :: web-platform-tests, defect, P5)

defect

Tracking

(Not tracked)

REOPENED

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

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


[task 2022-02-19T09:13:20.198Z] 09:13:20     INFO - TEST-START | /css/css-contain/container-queries/chrome-bug-1289718-000-crash.html
[task 2022-02-19T09:13:20.212Z] 09:13:20  WARNING - Traceback (most recent call last):
[task 2022-02-19T09:13:20.213Z] 09:13:20  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/base.py", line 297, in run_test
[task 2022-02-19T09:13:20.214Z] 09:13:20  WARNING -     self.on_environment_change(test.environment)
[task 2022-02-19T09:13:20.214Z] 09:13:20  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 1177, in on_environment_change
[task 2022-02-19T09:13:20.215Z] 09:13:20  WARNING -     self.protocol.on_environment_change(self.last_environment, new_environment)
[task 2022-02-19T09:13:20.216Z] 09:13:20  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 785, in on_environment_change
[task 2022-02-19T09:13:20.216Z] 09:13:20  WARNING -     self.executor.original_pref_values[name] = self.prefs.get(name)
[task 2022-02-19T09:13:20.216Z] 09:13:20  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 357, in get
[task 2022-02-19T09:13:20.216Z] 09:13:20  WARNING -     with self.marionette.using_context(self.marionette.CONTEXT_CHROME):
[task 2022-02-19T09:13:20.216Z] 09:13:20  WARNING -   File "/usr/lib/python3.6/contextlib.py", line 81, in __enter__
[task 2022-02-19T09:13:20.216Z] 09:13:20  WARNING -     return next(self.gen)
[task 2022-02-19T09:13:20.216Z] 09:13:20  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 1441, in using_context
[task 2022-02-19T09:13:20.217Z] 09:13:20  WARNING -     scope = self._send_message("Marionette:GetContext", key="value")
[task 2022-02-19T09:13:20.217Z] 09:13:20  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 37, in _
[task 2022-02-19T09:13:20.217Z] 09:13:20  WARNING -     m._handle_socket_failure()
[task 2022-02-19T09:13:20.217Z] 09:13:20  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 712, in _handle_socket_failure
[task 2022-02-19T09:13:20.217Z] 09:13:20  WARNING -     reraise(exc_cls, exc, tb)
[task 2022-02-19T09:13:20.217Z] 09:13:20  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/third_party/six/six.py", line 703, in reraise
[task 2022-02-19T09:13:20.217Z] 09:13:20  WARNING -     raise value
[task 2022-02-19T09:13:20.217Z] 09:13:20  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2022-02-19T09:13:20.217Z] 09:13:20  WARNING -     return func(*args, **kwargs)
[task 2022-02-19T09:13:20.217Z] 09:13:20  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 661, in _send_message
[task 2022-02-19T09:13:20.217Z] 09:13:20  WARNING -     msg = self.client.request(name, params)
[task 2022-02-19T09:13:20.217Z] 09:13:20  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 383, in request
[task 2022-02-19T09:13:20.217Z] 09:13:20  WARNING -     return self.receive()
[task 2022-02-19T09:13:20.217Z] 09:13:20  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 230, in receive
[task 2022-02-19T09:13:20.217Z] 09:13:20  WARNING -     raise socket.error("No data received over socket")
[task 2022-02-19T09:13:20.217Z] 09:13:20  WARNING - OSError: No data received over socket
[task 2022-02-19T09:13:20.218Z] 09:13:20  WARNING - 
[task 2022-02-19T09:13:20.218Z] 09:13:20     INFO - TEST-UNEXPECTED-ERROR | /css/css-contain/container-queries/chrome-bug-1289718-000-crash.html | Traceback (most recent call last):
[task 2022-02-19T09:13:20.218Z] 09:13:20     INFO -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/base.py", line 297, in run_test
[task 2022-02-19T09:13:20.218Z] 09:13:20     INFO -     self.on_environment_change(test.environment)
[task 2022-02-19T09:13:20.218Z] 09:13:20     INFO -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 1177, in on_environment_change
[task 2022-02-19T09:13:20.218Z] 09:13:20     INFO -     self.protocol.on_environment_change(self.last_environment, new_environment)
[task 2022-02-19T09:13:20.218Z] 09:13:20     INFO -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 785, in on_environment_change
[task 2022-02-19T09:13:20.218Z] 09:13:20     INFO -     self.executor.original_pref_values[name] = self.prefs.get(name)
[task 2022-02-19T09:13:20.218Z] 09:13:20     INFO -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 357, in get
[task 2022-02-19T09:13:20.218Z] 09:13:20     INFO -     with self.marionette.using_context(self.marionette.CONTEXT_CHROME):
[task 2022-02-19T09:13:20.218Z] 09:13:20     INFO -   File "/usr/lib/python3.6/contextlib.py", line 81, in __enter__
[task 2022-02-19T09:13:20.219Z] 09:13:20     INFO -     return next(self.gen)
[task 2022-02-19T09:13:20.219Z] 09:13:20     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 1441, in using_context
[task 2022-02-19T09:13:20.219Z] 09:13:20     INFO -     scope = self._send_message("Marionette:GetContext", key="value")
[task 2022-02-19T09:13:20.220Z] 09:13:20     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 37, in _
[task 2022-02-19T09:13:20.220Z] 09:13:20     INFO -     m._handle_socket_failure()
[task 2022-02-19T09:13:20.220Z] 09:13:20     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 712, in _handle_socket_failure
[task 2022-02-19T09:13:20.220Z] 09:13:20     INFO -     reraise(exc_cls, exc, tb)
[task 2022-02-19T09:13:20.221Z] 09:13:20     INFO -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/third_party/six/six.py", line 703, in reraise
[task 2022-02-19T09:13:20.221Z] 09:13:20     INFO -     raise value
[task 2022-02-19T09:13:20.221Z] 09:13:20     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2022-02-19T09:13:20.221Z] 09:13:20     INFO -     return func(*args, **kwargs)
[task 2022-02-19T09:13:20.221Z] 09:13:20     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 661, in _send_message
[task 2022-02-19T09:13:20.221Z] 09:13:20     INFO -     msg = self.client.request(name, params)
[task 2022-02-19T09:13:20.221Z] 09:13:20     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 383, in request
[task 2022-02-19T09:13:20.221Z] 09:13:20     INFO -     return self.receive()
[task 2022-02-19T09:13:20.221Z] 09:13:20     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 230, in receive
[task 2022-02-19T09:13:20.221Z] 09:13:20     INFO -     raise socket.error("No data received over socket")
[task 2022-02-19T09:13:20.221Z] 09:13:20     INFO - OSError: No data received over socket
[task 2022-02-19T09:13:20.221Z] 09:13:20     INFO - TEST-INFO took 20ms
[task 2022-02-19T09:13:20.223Z] 09:13:20     INFO - Browser exited with return code -15
[task 2022-02-19T09:13:20.278Z] 09:13:20     INFO - Closing logging queue
[task 2022-02-19T09:13:20.278Z] 09:13:20     INFO - queue closed
[task 2022-02-19T09:13:20.293Z] 09:13:20     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmp58_9064v
[task 2022-02-19T09:13:20.300Z] 09:13:20     INFO - Starting runner
[task 2022-02-19T09:13:20.310Z] 09:13:20     INFO - PID 3792 | 1645262000309	Marionette	INFO	Listening on port 53796
[task 2022-02-19T09:13:20.848Z] 09:13:20     INFO - PID 4130 | 1645262000847	Marionette	INFO	Marionette enabled
[task 2022-02-19T09:13:23.860Z] 09:13:23     INFO - PID 4130 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmp58_9064v/search.json.mozlz4", (void 0)))
[task 2022-02-19T09:13:24.395Z] 09:13:24     INFO - PID 4130 | 1645262004394	Marionette	INFO	Listening on port 40445
[task 2022-02-19T09:13:24.468Z] 09:13:24     INFO - TEST-START | /css/css-contain/contain-crash.html

This looks like some sporadic infra issue; doesn't look related to layout or related to this test in particular.

Looks like the badness starts here:


[task 2022-02-19T09:13:16.026Z] 09:13:16     INFO - PID 3730 | 1645261996025	Marionette	INFO	Marionette enabled
[task 2022-02-19T09:13:16.510Z] 09:13:16     INFO - PID 3792 | 1645261996509	Marionette	INFO	Marionette enabled
[task 2022-02-19T09:13:19.343Z] 09:13:19     INFO - PID 3730 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmp_f_5tebd/search.json.mozlz4", (void 0)))
[task 2022-02-19T09:13:19.702Z] 09:13:19     INFO - PID 3792 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmpqqmfeim9/search.json.mozlz4", (void 0)))
[task 2022-02-19T09:13:20.076Z] 09:13:20     INFO - PID 3730 | 1645262000075	Marionette	INFO	Listening on port 53796
[task 2022-02-19T09:13:20.195Z] 09:13:20  WARNING - Failed to start protocol connection
[task 2022-02-19T09:13:20.196Z] 09:13:20  WARNING - Traceback (most recent call last):
[task 2022-02-19T09:13:20.196Z] 09:13:20  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/protocol.py", line 61, in setup
[task 2022-02-19T09:13:20.196Z] 09:13:20  WARNING -     self.connect()
[task 2022-02-19T09:13:20.196Z] 09:13:20  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 749, in connect
[task 2022-02-19T09:13:20.196Z] 09:13:20  WARNING -     self.marionette.start_session(self.capabilities)
[task 2022-02-19T09:13:20.196Z] 09:13:20  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2022-02-19T09:13:20.196Z] 09:13:20  WARNING -     return func(*args, **kwargs)
[task 2022-02-19T09:13:20.196Z] 09:13:20  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 1217, in start_session
[task 2022-02-19T09:13:20.196Z] 09:13:20  WARNING -     resp = self._send_message("WebDriver:NewSession", capabilities)
[task 2022-02-19T09:13:20.197Z] 09:13:20  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2022-02-19T09:13:20.197Z] 09:13:20  WARNING -     return func(*args, **kwargs)
[task 2022-02-19T09:13:20.197Z] 09:13:20  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 668, in _send_message
[task 2022-02-19T09:13:20.198Z] 09:13:20  WARNING -     self._handle_error(err)
[task 2022-02-19T09:13:20.198Z] 09:13:20  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 680, in _handle_error
[task 2022-02-19T09:13:20.198Z] 09:13:20  WARNING -     raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2022-02-19T09:13:20.198Z] 09:13:20  WARNING - marionette_driver.errors.SessionNotCreatedException: Maximum number of active sessions
[task 2022-02-19T09:13:20.198Z] 09:13:20  WARNING - stacktrace:
[task 2022-02-19T09:13:20.198Z] 09:13:20  WARNING - 	WebDriverError@chrome://remote/content/shared/webdriver/Errors.jsm:183:5
[task 2022-02-19T09:13:20.198Z] 09:13:20  WARNING - 	SessionNotCreatedError@chrome://remote/content/shared/webdriver/Errors.jsm:453:5
[task 2022-02-19T09:13:20.198Z] 09:13:20  WARNING - 	GeckoDriver.prototype.newSession@chrome://remote/content/marionette/driver.js:408:11
[task 2022-02-19T09:13:20.198Z] 09:13:20  WARNING - 	despatch@chrome://remote/content/marionette/server.js:306:40
[task 2022-02-19T09:13:20.198Z] 09:13:20  WARNING - 	execute@chrome://remote/content/marionette/server.js:279:16
[task 2022-02-19T09:13:20.198Z] 09:13:20  WARNING - 	onPacket/<@chrome://remote/content/marionette/server.js:252:20
[task 2022-02-19T09:13:20.198Z] 09:13:20  WARNING - 	onPacket@chrome://remote/content/marionette/server.js:253:9
[task 2022-02-19T09:13:20.198Z] 09:13:20  WARNING - 	_onJSONObjectReady/<@chrome://remote/content/marionette/transport.js:500:20
[task 2022-02-19T09:13:20.198Z] 09:13:20  WARNING - 

The marionette_driver.errors.SessionNotCreatedException: Maximum number of active sessions part might be the relevant thing, I'm guessing? shrug

I'll tentatively reclassify this under the Marionette component -- though really it might not be a bug in marionette itself, but in some other piece of our automation environment. Hopefully this is at least one step closer to the right component, though, at least. :)

Component: Layout → Marionette
Product: Core → Testing

wptrunner seems to start Firefox twice but then tries to create a webdriver session twice for the same Firefox process which will not work:

https://treeherder.mozilla.org/logviewer?job_id=368467315&repo=autoland&lineNumber=1964

James, do you have an idea why that happens?

Component: Marionette → web-platform-tests
Flags: needinfo?(james)

This is odd. It's not impossible we're legitimately trying to start Firefox twice, but I don't know why we're trying to connect again to the same port. I suppose it's possible that we're trying to use the same port for two connections, but the implementation should make that almost impossible. In any case I've made a patch for bug 1753589 which would elimiate that problem.

Flags: needinfo?(james)
Summary: Intermittent /css/css-contain/container-queries/chrome-bug-1289718-000-crash.html | Traceback (most recent call last): | After OSError: No data received over socket → Intermittent <random test> | Traceback (most recent call last): | After OSError: No data received over socket
Duplicate of this bug: 1903482
Status: NEW → RESOLVED
Closed: 4 months ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
You need to log in before you can comment on or make changes to this bug.