1599086 - Intermittent awsy\test_memory_usage.py TestMemoryUsage.test_open_tabs | IOError: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Connection timed out after 360.0s)

Status: RESOLVED INCOMPLETE

(Testing :: AWSY, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: nerli [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=277996628&repo=try
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/TBa7i-h7REqKcacxgrXoag/runs/0/artifacts/public/logs/live_backing.log

---

[task 2019-11-25T12:55:22.024Z] 12:55:22 INFO - TEST-UNEXPECTED-ERROR | awsy\test_memory_usage.py TestMemoryUsage.test_open_tabs | IOError: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Connection timed out after 360.0s)
[task 2019-11-25T12:55:22.024Z] 12:55:22 INFO - Traceback (most recent call last):
[task 2019-11-25T12:55:22.024Z] 12:55:22 INFO - File "z:\task_1574683138\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
[task 2019-11-25T12:55:22.024Z] 12:55:22 INFO - testMethod()
[task 2019-11-25T12:55:22.024Z] 12:55:22 INFO - File "Z:\task_1574683138\build\tests\awsy\awsy\test_memory_usage.py", line 223, in test_open_tabs
[task 2019-11-25T12:55:22.024Z] 12:55:22 INFO - self.reset_state()
[task 2019-11-25T12:55:22.024Z] 12:55:22 INFO - File "z:\task_1574683138\build\venv\lib\site-packages\awsy\awsy_test_case.py", line 155, in reset_state
[task 2019-11-25T12:55:22.025Z] 12:55:22 INFO - self.marionette.close()
[task 2019-11-25T12:55:22.025Z] 12:55:22 INFO - File "z:\task_1574683138\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1285, in close
[task 2019-11-25T12:55:22.025Z] 12:55:22 INFO - return self._send_message("WebDriver:CloseWindow")
[task 2019-11-25T12:55:22.025Z] 12:55:22 INFO - File "z:\task_1574683138\build\venv\lib\site-packages\marionette_driver\decorators.py", line 36, in _
[task 2019-11-25T12:55:22.025Z] 12:55:22 INFO - m._handle_socket_failure()
[task 2019-11-25T12:55:22.025Z] 12:55:22 INFO - File "z:\task_1574683138\build\venv\lib\site-packages\marionette_driver\marionette.py", line 678, in _handle_socket_failure
[task 2019-11-25T12:55:22.025Z] 12:55:22 INFO - reraise(IOError, message.format(returncode=returncode, reason=val), tb)
[task 2019-11-25T12:55:22.025Z] 12:55:22 INFO - File "z:\task_1574683138\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
[task 2019-11-25T12:55:22.025Z] 12:55:22 INFO - return func(*args, **kwargs)
[task 2019-11-25T12:55:22.025Z] 12:55:22 INFO - File "z:\task_1574683138\build\venv\lib\site-packages\marionette_driver\marionette.py", line 590, in _send_message
[task 2019-11-25T12:55:22.026Z] 12:55:22 INFO - msg = self.client.request(name, params)
[task 2019-11-25T12:55:22.026Z] 12:55:22 INFO - File "z:\task_1574683138\build\venv\lib\site-packages\marionette_driver\transport.py", line 273, in request
[task 2019-11-25T12:55:22.026Z] 12:55:22 INFO - return self.receive()
[task 2019-11-25T12:55:22.026Z] 12:55:22 INFO - File "z:\task_1574683138\build\venv\lib\site-packages\marionette_driver\transport.py", line 183, in receive
[task 2019-11-25T12:55:22.026Z] 12:55:22 INFO - raise socket.timeout("Connection timed out after {}s".format(self.socket_timeout))
[task 2019-11-25T12:55:22.026Z] 12:55:22 INFO - TEST-INFO took 982082ms
[task 2019-11-25T12:55:22.027Z] 12:55:22 INFO - tearing down!
[task 2019-11-25T12:55:22.027Z] 12:55:22 INFO - tearing down webservers!
[task 2019-11-25T12:55:22.027Z] 12:55:22 INFO - mozproxy Stopping mitmproxy playback, killing process 10332
[task 2019-11-25T12:55:22.089Z] 12:55:22 INFO - mozproxy Mitmproxy exited with error code 572
[task 2019-11-25T12:55:22.090Z] 12:55:22 INFO - mozproxy Turning off the browser proxy
[task 2019-11-25T12:55:22.090Z] 12:55:22 INFO - mozproxy writing: Z:\task_1574683138\build\application\firefox\distribution\policies.json
[task 2019-11-25T12:55:22.091Z] 12:55:22 INFO - Application command: Z:\task_1574683138\build\application\firefox\firefox.exe -no-remote -marionette --wait-for-browser -profile c:\users\task_1574683138\appdata\local\temp\tmpkd9xzj.mozrunner
[task 2019-11-25T12:55:27.589Z] 12:55:27 INFO - processing data in Z:\task_1574683138\build\tests\results!

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• windows10-64-shippable-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1599086&startday=2019-11-25&endday=2019-12-01&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• windows10-64: 1
• windows10-64-qr: 1

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

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 2

Platform breakdown:

• windows10-64-shippable-qr: 1
• windows10-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1599086&startday=2019-12-09&endday=2019-12-15&tree=all

Comment 4

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.