1675175 - Intermittent [tier 2] MnFr 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

Status: RESOLVED INVALID

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

Description

[Treeherder Bug Filer]

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

---

[task 2020-11-04T03:04:10.487Z] 03:04:10     INFO -  TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py TestNavigate.test_type_to_remote_tab
[task 2020-11-04T03:07:57.825Z] 03:07:57     INFO -  DEBUG: Completed blocker places.sqlite#2: PlacesUtils: promiseKeywordsCache (0) for phase places.sqlite#2: waiting for clients
[task 2020-11-04T03:07:58.709Z] 03:07:58     INFO -  [Parent 1320, Jump List] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012 (NS_ERROR_FILE_NOT_FOUND): file /builds/worker/checkouts/gecko/widget/windows/WinUtils.cpp:1391
[task 2020-11-04T03:10:11.206Z] 03:10:11     INFO -  1604459411203	Marionette	DEBUG	Closed connection 21
[task 2020-11-04T03:11:22.334Z] 03:11:22    ERROR -  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-11-04T03:11:22.334Z] 03:11:22     INFO -  Traceback (most recent call last):
[task 2020-11-04T03:11:22.334Z] 03:11:22     INFO -    File "C:\Users\task_1604456738\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 214, in run
[task 2020-11-04T03:11:22.334Z] 03:11:22     INFO -      testMethod()
[task 2020-11-04T03:11:22.334Z] 03:11:22     INFO -    File "C:\Users\task_1604456738\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_navigation.py", line 380, in test_type_to_remote_tab
[task 2020-11-04T03:11:22.335Z] 03:11:22     INFO -      message="'{}' hasn't been loaded".format(self.test_page_remote),
[task 2020-11-04T03:11:22.335Z] 03:11:22     INFO -    File "C:\Users\task_1604456738\build\venv\lib\site-packages\marionette_driver\wait.py", line 134, in until
[task 2020-11-04T03:11:22.335Z] 03:11:22     INFO -      rv = condition(self.marionette)
[task 2020-11-04T03:11:22.336Z] 03:11:22     INFO -    File "C:\Users\task_1604456738\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_navigation.py", line 379, in <lambda>
[task 2020-11-04T03:11:22.336Z] 03:11:22     INFO -      lambda mn: mn.get_url() == self.test_page_remote,
[task 2020-11-04T03:11:22.336Z] 03:11:22     INFO -    File "C:\Users\task_1604456738\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1464, in get_url
[task 2020-11-04T03:11:22.336Z] 03:11:22     INFO -      return self._send_message("WebDriver:GetCurrentURL", key="value")
[task 2020-11-04T03:11:22.336Z] 03:11:22     INFO -    File "C:\Users\task_1604456738\build\venv\lib\site-packages\marionette_driver\decorators.py", line 37, in _
[task 2020-11-04T03:11:22.336Z] 03:11:22     INFO -      m._handle_socket_failure()
[task 2020-11-04T03:11:22.336Z] 03:11:22     INFO -    File "C:\Users\task_1604456738\build\venv\lib\site-packages\marionette_driver\marionette.py", line 712, in _handle_socket_failure
[task 2020-11-04T03:11:22.337Z] 03:11:22     INFO -      IOError, IOError(message.format(returncode=returncode, reason=exc)), tb
[task 2020-11-04T03:11:22.337Z] 03:11:22     INFO -    File "C:\Users\task_1604456738\build\venv\lib\site-packages\marionette_driver\decorators.py", line 27, in _
[task 2020-11-04T03:11:22.337Z] 03:11:22     INFO -      return func(*args, **kwargs)
[task 2020-11-04T03:11:22.337Z] 03:11:22     INFO -    File "C:\Users\task_1604456738\build\venv\lib\site-packages\marionette_driver\marionette.py", line 617, in _send_message
[task 2020-11-04T03:11:22.337Z] 03:11:22     INFO -      msg = self.client.request(name, params)
[task 2020-11-04T03:11:22.337Z] 03:11:22     INFO -    File "C:\Users\task_1604456738\build\venv\lib\site-packages\marionette_driver\transport.py", line 288, in request
[task 2020-11-04T03:11:22.337Z] 03:11:22     INFO -      return self.receive()
[task 2020-11-04T03:11:22.337Z] 03:11:22     INFO -    File "C:\Users\task_1604456738\build\venv\lib\site-packages\marionette_driver\transport.py", line 192, in receive
[task 2020-11-04T03:11:22.338Z] 03:11:22     INFO -      "Connection timed out after {}s".format(self.socket_timeout)
[task 2020-11-04T03:11:22.338Z] 03:11:22     INFO -  TEST-INFO took 431846ms
[task 2020-11-04T03:11:22.338Z] 03:11:22     INFO -  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 \"C:\\Users\\task_1604456738\\build\\venv\\lib\\site-packages\\marionette_harness\\marionette_test\\testcases.py\", line 247, in run\n    self.tearDown()\n  File \"C:\\Users\\task_1604456738\\build\\tests\\marionette\\tests\\testing\\marionette\\harness\\marionette_harness\\tests\\unit\\test_navigation.py\", line 69, in tearDown\n    self.marionette.timeout.reset()\n  File \"C:\\Users\\task_1604456738\\build\\venv\\lib\\site-packages\\marionette_driver\\timeout.py\", line 104, in reset\n    self.script = DEFAULT_SCRIPT_TIMEOUT\n  File \"C:\\Users\\task_1604456738\\build\\venv\\lib\\site-packages\\marionette_driver\\timeout.py\", line 57, in script\n    self._set(\"script\", sec)\n  File \"C:\\Users\\task_1604456738\\build\\venv\\lib\\site-packages\\marionette_driver\\timeout.py\", line 33, in _set\n    self._marionette._send_message(\"WebDriver:SetTimeouts\", {name: ms})\n  File \"C:\\Users\\task_1604456738\\build\\venv\\lib\\site-packages\\marionette_driver\\decorators.py\", line 27, in _\n    return func(*args, **kwargs)\n  File \"C:\\Users\\task_1604456738\\build\\venv\\lib\\site-packages\\marionette_driver\\marionette.py\", line 614, in _send_message\n    raise errors.InvalidSessionIdException(\"Please start a session\")\n"}```

Comment 1

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

Hangs when trying to retrieve the current URL:

[task 2020-11-04T03:04:11.180Z] 03:04:11     INFO -  1604459051171	Marionette	DEBUG	21 -> [0,30,"WebDriver:GetCurrentURL",{}]
[task 2020-11-04T03:04:11.180Z] 03:04:11     INFO -  1604459051172	Marionette	TRACE	[84] Frame script unloaded
[task 2020-11-04T03:04:11.180Z] 03:04:11     INFO -  [Child 4548, Main Thread] WARNING: NS_ENSURE_TRUE(browserChrome) failed: file /builds/worker/checkouts/gecko/docshell/base/nsDocShell.cpp:12096
[task 2020-11-04T03:04:11.197Z] 03:04:11     INFO -  1604459051183	Marionette	TRACE	[84] Frame script loaded
[task 2020-11-04T03:04:11.214Z] 03:04:11     INFO -  1604459051205	Marionette	TRACE	Detected remoteness change. New browsing context: 84

To get this fixed our actors have to be enabled for all builds. See bug 1669169.

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3

Platform breakdown:

• windows7-32: 2
• windows10-64: 1

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

Comment 3

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

This failure only appeared on Nov 4th and since then no more again. Closing as incomplete for now.

Comment 4

[Razvan Maries]

New occurrence: https://treeherder.mozilla.org/logviewer?job_id=323529094&repo=autoland&lineNumber=21636

Comment 5

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

This should only happen for the with the old framescript code.

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• windows7-32: 1
• macosx1014-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1675175&startday=2020-11-30&endday=2020-12-06&tree=all

Comment 7

[Intermittent Failures Robot]

1 failures in 4060 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1675175&startday=2020-12-07&endday=2020-12-13&tree=all

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3

Platform breakdown:

• windows7-32: 3

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

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• windows7-32: 2

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

Comment 10

[Intermittent Failures Robot]

1 failures in 2956 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• macosx1014-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1675175&startday=2021-01-04&endday=2021-01-10&tree=all

Comment 11

[Intermittent Failures Robot]

1 failures in 3810 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1675175&startday=2021-01-11&endday=2021-01-17&tree=all

Comment 12

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• windows7-32: 1
  • opt: 1
• macosx1014-64-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1675175&startday=2021-01-25&endday=2021-01-31&tree=all

Comment 13

[Intermittent Failures Robot]

2 failures in 4322 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• windows7-32: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1675175&startday=2021-02-08&endday=2021-02-14&tree=all

Comment 14

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

With all the framescript code removed (bug 1669172) this bug is invalid now.

Comment 15

[BMO Automation]

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