1659944 - Intermittent testing/marionette/harness/marionette_harness/tests/unit/test_profile_management.py TestQuitRestartWithWorkspace.test_restart_keeps_same_profile | IOError: Process killed because the connection to Marionette server is lost. Check gecko.log fo

Status: RESOLVED DUPLICATE of bug 1527674

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

Description

[Treeherder Bug Filer]

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

---

[task 2020-08-19T08:41:21.985Z] 08:41:21 INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_profile_management.py TestQuitRestartWithWorkspace.test_restart_keeps_same_profile
[task 2020-08-19T08:41:21.985Z] 08:41:21 INFO - 1597826481983 Marionette DEBUG Closed connection 3
[task 2020-08-19T08:41:21.990Z] 08:41:21 INFO - 1597826481985 Marionette DEBUG Accepted connection 4 from 127.0.0.1:51602
[task 2020-08-19T08:41:21.990Z] 08:41:21 INFO - 1597826481986 Marionette DEBUG 4 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2020-08-19T08:41:21.990Z] 08:41:21 INFO - 1597826481988 Marionette TRACE [20] Frame script loaded
[task 2020-08-19T08:41:21.992Z] 08:41:21 INFO - 1597826481989 Marionette TRACE [20] Frame script registered
[task 2020-08-19T08:41:21.992Z] 08:41:21 INFO - 1597826481990 Marionette DEBUG 4 <- [1,1,null,{"sessionId":"0193bd10-ca62-412a-9dae-262b0b832a08","capabilities":{"browserName":"firefox","browserVersion":"81.0a ... o\tmpxwtgyv.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2020-08-19T08:41:21.994Z] 08:41:21 INFO - 1597826481992 Marionette DEBUG 4 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2020-08-19T08:41:21.994Z] 08:41:21 INFO - 1597826481992 Marionette DEBUG 4 <- [1,2,null,{"value":null}]
[task 2020-08-19T08:41:21.996Z] 08:41:21 INFO - 1597826481994 Marionette DEBUG 4 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2020-08-19T08:41:21.996Z] 08:41:21 INFO - 1597826481994 Marionette DEBUG 4 <- [1,3,null,{"value":null}]
[task 2020-08-19T08:41:21.998Z] 08:41:21 INFO - 1597826481995 Marionette DEBUG 4 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2020-08-19T08:41:21.998Z] 08:41:21 INFO - 1597826481996 Marionette DEBUG 4 <- [1,4,null,{"value":null}]
[task 2020-08-19T08:41:22.000Z] 08:41:22 INFO - 1597826481998 Marionette DEBUG 4 -> [0,5,"Marionette:GetContext",{}]
[task 2020-08-19T08:41:22.000Z] 08:41:22 INFO - 1597826481998 Marionette DEBUG 4 <- [1,5,null,{"value":"content"}]
[task 2020-08-19T08:41:22.002Z] 08:41:22 INFO - 1597826482000 Marionette DEBUG 4 -> [0,6,"WebDriver:DeleteSession",{}]
[task 2020-08-19T08:41:22.004Z] 08:41:22 INFO - 1597826482001 Marionette DEBUG 4 <- [1,6,null,{"value":null}]
[task 2020-08-19T08:41:22.075Z] 08:41:22 INFO - Application command: Z:\task_1597826019\build\application\firefox\firefox.exe -no-remote -marionette --wait-for-browser -profile c:\users\task_1597826019\appdata\local\temp\tmpgxzvto\tmpxwtgyv.mozrunner
[task 2020-08-19T08:44:33.409Z] 08:44:33 ERROR - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_profile_management.py TestQuitRestartWithWorkspace.test_restart_keeps_same_profile | IOError: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Timed out waiting for connection on 127.0.0.1:2828!)
[task 2020-08-19T08:44:33.409Z] 08:44:33 INFO - Traceback (most recent call last):
[task 2020-08-19T08:44:33.409Z] 08:44:33 INFO - File "Z:\task_1597826019\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 196, in run
[task 2020-08-19T08:44:33.409Z] 08:44:33 INFO - testMethod()
[task 2020-08-19T08:44:33.409Z] 08:44:33 INFO - File "Z:\task_1597826019\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py", line 127, in test_restart_keeps_same_profile
[task 2020-08-19T08:44:33.409Z] 08:44:33 INFO - self.marionette.restart()
[task 2020-08-19T08:44:33.409Z] 08:44:33 INFO - File "Z:\task_1597826019\build\venv\lib\site-packages\marionette_driver\decorators.py", line 36, in _
[task 2020-08-19T08:44:33.409Z] 08:44:33 INFO - m._handle_socket_failure()
[task 2020-08-19T08:44:33.409Z] 08:44:33 INFO - File "Z:\task_1597826019\build\venv\lib\site-packages\marionette_driver\marionette.py", line 682, in _handle_socket_failure
[task 2020-08-19T08:44:33.409Z] 08:44:33 INFO - reraise(IOError, IOError(message.format(returncode=returncode, reason=exc)), tb)
[task 2020-08-19T08:44:33.410Z] 08:44:33 INFO - File "Z:\task_1597826019\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
[task 2020-08-19T08:44:33.410Z] 08:44:33 INFO - return func(*args, **kwargs)
[task 2020-08-19T08:44:33.410Z] 08:44:33 INFO - File "Z:\task_1597826019\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1056, in restart
[task 2020-08-19T08:44:33.410Z] 08:44:33 INFO - self.raise_for_port(timeout=self.DEFAULT_STARTUP_TIMEOUT)
[task 2020-08-19T08:44:33.410Z] 08:44:33 INFO - File "Z:\task_1597826019\build\venv\lib\site-packages\marionette_driver\marionette.py", line 573, in raise_for_port
[task 2020-08-19T08:44:33.410Z] 08:44:33 INFO - self.host, self.port))
[task 2020-08-19T08:44:33.410Z] 08:44:33 INFO - TEST-INFO took 191416ms

Comment 1

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

Firefox hangs during startup, including the missing marionette-startup-requested observer notification.

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows10-64: 1

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

Comment 3

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

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

Comment 4

[Razvan Maries]

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

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3

Platform breakdown:

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

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

Comment 7

[BMO Automation]

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