1664968 - Intermittent [tier2] Mn-fis testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py TestNavigate.test_type_to_remote_tab | IOError: Process killed because the connection to Marionette server is lost. Check gecko.log for errors

Status: RESOLVED FIXED

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

Description

[Treeherder Bug Filer]

Filed by: rmaries [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=315708851&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/C-zmvloXSuueSGFj1L-5zw/runs/0/artifacts/public/logs/live_backing.log

---

[task 2020-09-15T03:19:57.479Z] 03:19:57     INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py TestNavigate.test_type_to_remote_tab
[task 2020-09-15T03:25:58.232Z] 03:25:58     INFO -  1600140358225	Marionette	DEBUG	Closed connection 19
[task 2020-09-15T03:27:09.287Z] 03:27:09     INFO - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py TestNavigate.test_type_to_remote_tab | IOError: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Connection timed out after 360.0s)
[task 2020-09-15T03:27:09.287Z] 03:27:09     INFO - Traceback (most recent call last):
[task 2020-09-15T03:27:09.287Z] 03:27:09     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 196, in run
[task 2020-09-15T03:27:09.287Z] 03:27:09     INFO -     testMethod()
[task 2020-09-15T03:27:09.288Z] 03:27:09     INFO -   File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py", line 337, in test_type_to_remote_tab
[task 2020-09-15T03:27:09.288Z] 03:27:09     INFO -     message="'{}' hasn't been loaded".format(self.test_page_remote))
[task 2020-09-15T03:27:09.288Z] 03:27:09     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/wait.py", line 129, in until
[task 2020-09-15T03:27:09.288Z] 03:27:09     INFO -     rv = condition(self.marionette)
[task 2020-09-15T03:27:09.288Z] 03:27:09     INFO -   File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py", line 336, in <lambda>
[task 2020-09-15T03:27:09.288Z] 03:27:09     INFO -     lambda mn: mn.get_url() == self.test_page_remote,
[task 2020-09-15T03:27:09.288Z] 03:27:09     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1424, in get_url
[task 2020-09-15T03:27:09.288Z] 03:27:09     INFO -     key="value")
[task 2020-09-15T03:27:09.288Z] 03:27:09     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 36, in _
[task 2020-09-15T03:27:09.288Z] 03:27:09     INFO -     m._handle_socket_failure()
[task 2020-09-15T03:27:09.288Z] 03:27:09     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 682, in _handle_socket_failure
[task 2020-09-15T03:27:09.288Z] 03:27:09     INFO -     reraise(IOError, IOError(message.format(returncode=returncode, reason=exc)), tb)
[task 2020-09-15T03:27:09.288Z] 03:27:09     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2020-09-15T03:27:09.288Z] 03:27:09     INFO -     return func(*args, **kwargs)
[task 2020-09-15T03:27:09.288Z] 03:27:09     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 594, in _send_message
[task 2020-09-15T03:27:09.289Z] 03:27:09     INFO -     msg = self.client.request(name, params)
[task 2020-09-15T03:27:09.289Z] 03:27:09     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/transport.py", line 276, in request
[task 2020-09-15T03:27:09.289Z] 03:27:09     INFO -     return self.receive()
[task 2020-09-15T03:27:09.289Z] 03:27:09     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/transport.py", line 186, in receive
[task 2020-09-15T03:27:09.289Z] 03:27:09     INFO -     raise socket.timeout("Connection timed out after {}s".format(self.socket_timeout))
[task 2020-09-15T03:27:09.289Z] 03:27:09     INFO - TEST-INFO took 431809ms
[task 2020-09-15T03:27:09.289Z] 03:27:09    ERROR - test_end for testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py TestNavigate.test_type_to_remote_tab logged while not in progress. Logged with data: {"status": "ERROR", "extra": {"class_name": "test_navigation.TestNavigate", "method_name": "test_type_to_remote_tab"}, "expected": "PASS", "test": "testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py TestNavigate.test_type_to_remote_tab", "message": "InvalidSessionIdException: Please start a session", "stack": "Traceback (most recent call last):\n  File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py\", line 227, in run\n    self.tearDown()\n  File \"/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py\", line 65, in tearDown\n    self.marionette.timeout.reset()\n  File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/timeout.py\", line 104, in reset\n    self.script = DEFAULT_SCRIPT_TIMEOUT\n  File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/timeout.py\", line 57, in script\n    self._set(\"script\", sec)\n  File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/timeout.py\", line 33, in _set\n    self._marionette._send_message(\"WebDriver:SetTimeouts\", {name: ms})\n  File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py\", line 26, in _\n    return func(*args, **kwargs)\n  File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py\", line 591, in _send_message\n    raise errors.InvalidSessionIdException(\"Please start a session\")\n"}

Comment 1

[Henrik Skupin [:whimboo][⌚️UTC+1]]

The WebDriver:GetCurrentURL command hangs here due to an unexpected remoteness change:

[task 2020-09-15T03:19:58.143Z] 03:19:58     INFO -  1600139998140	Marionette	DEBUG	19 -> [0,28,"WebDriver:GetCurrentURL",{}]
[task 2020-09-15T03:19:58.149Z] 03:19:58     INFO -  [Child 3386, Main Thread] WARNING: NS_ENSURE_TRUE(browserChrome) failed: file /builds/worker/checkouts/gecko/docshell/base/nsDocShell.cpp, line 11750
[task 2020-09-15T03:19:58.179Z] 03:19:58     INFO -  1600139998177	Marionette	TRACE	[82] Frame script loaded
[task 2020-09-15T03:19:58.180Z] 03:19:58     INFO -  1600139998178	Marionette	TRACE	[82] Frame script registered
[task 2020-09-15T03:19:58.183Z] 03:19:58     INFO -  1600139998181	Marionette	TRACE	[76] Received observer notification outer-window-destroyed
[task 2020-09-15T03:19:58.184Z] 03:19:58     INFO -  1600139998181	Marionette	TRACE	Detected remoteness change. New browsing context: 82

Right now this is basically based on bug 1347458 because we had to use the framescript again (bug 1663641). Bug 1664881 should get it fixed.

Comment 2

[Intermittent Failures Robot]

15 failures in 3760 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 12
• try: 2
• mozilla-central: 1

Platform breakdown:

• linux1804-64: 6
• windows10-64: 8
• windows10-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1664968&startday=2020-09-14&endday=2020-09-20&tree=all

Comment 3

[Intermittent Failures Robot]

7 failures in 4019 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 6
• try: 1

Platform breakdown:

• windows10-64: 4
• linux1804-64: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1664968&startday=2020-09-21&endday=2020-09-27&tree=all

Comment 4

[Intermittent Failures Robot]

3 failures in 4195 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 3

Platform breakdown:

• linux1804-64: 1
• windows10-64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1664968&startday=2020-09-28&endday=2020-10-04&tree=all

Comment 5

[Intermittent Failures Robot]

14 failures in 3694 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 14

Platform breakdown:

• linux1804-64: 6
• windows10-64: 8

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1664968&startday=2020-10-12&endday=2020-10-18&tree=all

Comment 6

[Henrik Skupin [:whimboo][⌚️UTC+1]]

I haven't seen this bug with actors enabled yet. So having bug 1660168 fixed should also fix this one.

Comment 7

[Intermittent Failures Robot]

50 failures in 4230 pushes (0.012 failures/push) were associated with this bug in the last 7 days.

This is the #18 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 50

Platform breakdown:

• linux1804-64: 14
• windows10-64: 36

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1664968&startday=2020-10-19&endday=2020-10-25&tree=all

Comment 8

[Intermittent Failures Robot]

16 failures in 692 pushes (0.023 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 15
• try: 1

Platform breakdown:

• windows10-64: 12
• linux1804-64: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1664968&startday=2020-10-26&endday=2020-10-26&tree=all

Comment 9

[Henrik Skupin [:whimboo][⌚️UTC+1]]

This bug has been indeed fixed by turning on the Actor implementation in Marionette, and for Fission we do not want to support the framescript anytime longer.

Comment 10

[Intermittent Failures Robot]

26 failures in 4275 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 25
• try: 1

Platform breakdown:

• windows10-64: 19
• linux1804-64: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1664968&startday=2020-10-26&endday=2020-11-01&tree=all

Comment 11

[BMO Automation]

Moving bug to Testing::Marionette Client and Harness component per bug 1815831.