Closed Bug 1739275 Opened 4 years ago Closed 4 years ago

Intermittent testing/marionette/harness/marionette_harness/tests/unit/test_execute_async_script.py TestExecuteAsyncContent.test_execute_js_exception | marionette_driver.errors.SessionNotCreatedException: TimeoutError: TimedPromise timed out after 300000

Categories

(Testing :: Marionette Client and Harness, defect, P5)

Product:
Testing
Component:
Marionette Client and Harness
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED DUPLICATE of bug 1739008

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2021-11-03T23:30:57.201Z] 23:30:57     INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_execute_async_script.py TestExecuteAsyncContent.test_execute_js_exception
[task 2021-11-03T23:30:57.208Z] 23:30:57     INFO -  1635982257205	Marionette	DEBUG	Closed connection 349
[task 2021-11-03T23:30:57.209Z] 23:30:57     INFO -  1635982257206	Marionette	DEBUG	Accepted connection 350 from 127.0.0.1:56830
[task 2021-11-03T23:30:57.212Z] 23:30:57     INFO -  1635982257212	Marionette	DEBUG	350 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2021-11-03T23:31:10.400Z] 23:31:10     INFO -  DEBUG: Adding blocker SessionFile: Finish writing Session Restore data for phase profile-before-change
[task 2021-11-03T23:31:10.404Z] 23:31:10     INFO -  DEBUG: Completed blocker SessionFile: Finish writing Session Restore data for phase profile-before-change
[task 2021-11-03T23:31:25.407Z] 23:31:25     INFO -  DEBUG: Adding blocker SessionFile: Finish writing Session Restore data for phase profile-before-change
[task 2021-11-03T23:31:25.411Z] 23:31:25     INFO -  DEBUG: Completed blocker SessionFile: Finish writing Session Restore data for phase profile-before-change
[task 2021-11-03T23:35:57.216Z] 23:35:57     INFO -  1635982557215	Marionette	DEBUG	350 <- [1,1,{"error":"session not created","message":"TimeoutError: TimedPromise timed out after 300000 ms","stacktrace":"WebDriverE ... r@chrome://remote/content/shared/webdriver/Errors.jsm:450:5\nbail@chrome://remote/content/marionette/sync.js:230:19\n"},null]
[task 2021-11-03T23:35:57.220Z] 23:35:57     INFO - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_execute_async_script.py TestExecuteAsyncContent.test_execute_js_exception | marionette_driver.errors.SessionNotCreatedException: TimeoutError: TimedPromise timed out after 300000 ms
[task 2021-11-03T23:35:57.221Z] 23:35:57     INFO - stacktrace:
[task 2021-11-03T23:35:57.221Z] 23:35:57     INFO - 	WebDriverError@chrome://remote/content/shared/webdriver/Errors.jsm:181:5
[task 2021-11-03T23:35:57.222Z] 23:35:57     INFO - 	TimeoutError@chrome://remote/content/shared/webdriver/Errors.jsm:450:5
[task 2021-11-03T23:35:57.222Z] 23:35:57     INFO - 	bail@chrome://remote/content/marionette/sync.js:230:19
[task 2021-11-03T23:35:57.223Z] 23:35:57     INFO - Traceback (most recent call last):
[task 2021-11-03T23:35:57.223Z] 23:35:57     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_harness/marionette_test/testcases.py", line 183, in run
[task 2021-11-03T23:35:57.223Z] 23:35:57     INFO -     self.setUp()
[task 2021-11-03T23:35:57.223Z] 23:35:57     INFO -   File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_execute_async_script.py", line 18, in setUp
[task 2021-11-03T23:35:57.223Z] 23:35:57     INFO -     super(TestExecuteAsyncContent, self).setUp()
[task 2021-11-03T23:35:57.223Z] 23:35:57     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_harness/marionette_test/testcases.py", line 405, in setUp
[task 2021-11-03T23:35:57.224Z] 23:35:57     INFO -     super(MarionetteTestCase, self).setUp()
[task 2021-11-03T23:35:57.224Z] 23:35:57     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_harness/marionette_test/testcases.py", line 308, in setUp
[task 2021-11-03T23:35:57.224Z] 23:35:57     INFO -     self.marionette.start_session()
[task 2021-11-03T23:35:57.225Z] 23:35:57     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2021-11-03T23:35:57.225Z] 23:35:57     INFO -     return func(*args, **kwargs)
[task 2021-11-03T23:35:57.225Z] 23:35:57     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 1187, in start_session
[task 2021-11-03T23:35:57.225Z] 23:35:57     INFO -     resp = self._send_message("WebDriver:NewSession", capabilities)
[task 2021-11-03T23:35:57.225Z] 23:35:57     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2021-11-03T23:35:57.225Z] 23:35:57     INFO -     return func(*args, **kwargs)
[task 2021-11-03T23:35:57.225Z] 23:35:57     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 630, in _send_message
[task 2021-11-03T23:35:57.225Z] 23:35:57     INFO -     self._handle_error(err)
[task 2021-11-03T23:35:57.225Z] 23:35:57     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 652, in _handle_error
[task 2021-11-03T23:35:57.225Z] 23:35:57     INFO -     raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2021-11-03T23:35:57.225Z] 23:35:57     INFO - TEST-INFO took 300019ms
[task 2021-11-03T23:35:57.226Z] 23:35:57     INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_execute_async_script.py TestExecuteAsyncContent.test_execute_no_return
[task 2021-11-03T23:35:57.226Z] 23:35:57     INFO -  1635982557219	Marionette	WARN	Connection attempt denied because an active session has been found
[task 2021-11-03T23:35:57.227Z] 23:35:57     INFO -  JavaScript error: chrome://remote/content/marionette/driver.js, line 2113: NS_ERROR_ILLEGAL_VALUE: Component returned failure code: 0x80070057 (NS_ERROR_ILLEGAL_VALUE) [nsIObserverService.removeObserver]
[task 2021-11-03T23:37:08.285Z] 23:37:08     INFO - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_execute_async_script.py TestExecuteAsyncContent.test_execute_no_return | OSError: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: No data received over socket)
[task 2021-11-03T23:37:08.285Z] 23:37:08     INFO - Traceback (most recent call last):
[task 2021-11-03T23:37:08.286Z] 23:37:08     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_harness/marionette_test/testcases.py", line 183, in run
[task 2021-11-03T23:37:08.286Z] 23:37:08     INFO -     self.setUp()
[task 2021-11-03T23:37:08.286Z] 23:37:08     INFO -   File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_execute_async_script.py", line 18, in setUp
[task 2021-11-03T23:37:08.287Z] 23:37:08     INFO -     super(TestExecuteAsyncContent, self).setUp()
[task 2021-11-03T23:37:08.287Z] 23:37:08     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_harness/marionette_test/testcases.py", line 405, in setUp
[task 2021-11-03T23:37:08.287Z] 23:37:08     INFO -     super(MarionetteTestCase, self).setUp()
[task 2021-11-03T23:37:08.287Z] 23:37:08     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_harness/marionette_test/testcases.py", line 308, in setUp
[task 2021-11-03T23:37:08.287Z] 23:37:08     INFO -     self.marionette.start_session()
[task 2021-11-03T23:37:08.287Z] 23:37:08     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 37, in _
[task 2021-11-03T23:37:08.287Z] 23:37:08     INFO -     m._handle_socket_failure()
[task 2021-11-03T23:37:08.287Z] 23:37:08     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 717, in _handle_socket_failure
[task 2021-11-03T23:37:08.287Z] 23:37:08     INFO -     IOError, IOError(message.format(returncode=returncode, reason=exc)), tb
[task 2021-11-03T23:37:08.287Z] 23:37:08     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/six.py", line 702, in reraise
[task 2021-11-03T23:37:08.287Z] 23:37:08     INFO -     raise value.with_traceback(tb)
[task 2021-11-03T23:37:08.287Z] 23:37:08     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2021-11-03T23:37:08.287Z] 23:37:08     INFO -     return func(*args, **kwargs)
[task 2021-11-03T23:37:08.287Z] 23:37:08     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 1184, in start_session
[task 2021-11-03T23:37:08.287Z] 23:37:08     INFO -     self.protocol, _ = self.client.connect()
[task 2021-11-03T23:37:08.288Z] 23:37:08     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 310, in connect
[task 2021-11-03T23:37:08.288Z] 23:37:08     INFO -     raw = self.receive(unmarshal=False)
[task 2021-11-03T23:37:08.288Z] 23:37:08     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 230, in receive
[task 2021-11-03T23:37:08.288Z] 23:37:08     INFO -     raise socket.error("No data received over socket")
[task 2021-11-03T23:37:08.288Z] 23:37:08     INFO - TEST-INFO took 71064ms
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → DUPLICATE
Product: Testing → Remote Protocol
Moving bug to Testing::Marionette Client and Harness component per bug 1815831.
Component: Marionette → Marionette Client and Harness
Product: Remote Protocol → Testing
You need to log in before you can comment on or make changes to this bug.