Closed Bug 1664915 Opened 4 years ago Closed 2 years ago

Intermittent Process killed after 120s because no connection to Marionette server could be established. Check gecko.log for errors

Categories

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

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

Tracking

(firefox106 fixed)

Status:
RESOLVED FIXED
Milestone:
106 Branch
Tracking Flags:
Tracking Status
firefox106 --- fixed

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

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

[task 2020-09-14T20:07:31.937Z] 20:07:31     INFO - Application command: Z:\task_1600113784\build\application\firefox\firefox.exe -no-remote -marionette --wait-for-browser -profile c:\users\task_1600113784\appdata\local\temp\tmprdgfd2.profile
[task 2020-09-14T20:09:32.318Z] 20:09:32    ERROR - Process killed after 120s because no connection to Marionette server could be established. Check gecko.log for errors
[task 2020-09-14T20:09:32.318Z] 20:09:32    ERROR - Traceback (most recent call last):
[task 2020-09-14T20:09:32.318Z] 20:09:32    ERROR - 
[task 2020-09-14T20:09:32.318Z] 20:09:32    ERROR -   File "z:\task_1600113784\build\venv\lib\site-packages\marionette_harness\runtests.py", line 91, in cli
[task 2020-09-14T20:09:32.318Z] 20:09:32    ERROR -     failed = harness_instance.run()
[task 2020-09-14T20:09:32.318Z] 20:09:32    ERROR - 
[task 2020-09-14T20:09:32.318Z] 20:09:32    ERROR -   File "z:\task_1600113784\build\venv\lib\site-packages\marionette_harness\runtests.py", line 71, in run
[task 2020-09-14T20:09:32.318Z] 20:09:32    ERROR -     runner.run_tests(tests)
[task 2020-09-14T20:09:32.318Z] 20:09:32    ERROR - 
[task 2020-09-14T20:09:32.318Z] 20:09:32    ERROR -   File "z:\task_1600113784\build\venv\lib\site-packages\marionette_harness\runner\base.py", line 850, in run_tests
[task 2020-09-14T20:09:32.318Z] 20:09:32    ERROR -     self.marionette = self.driverclass(**self._build_kwargs())
[task 2020-09-14T20:09:32.318Z] 20:09:32    ERROR - 
[task 2020-09-14T20:09:32.318Z] 20:09:32    ERROR -   File "z:\task_1600113784\build\venv\lib\site-packages\marionette_driver\marionette.py", line 465, in __init__
[task 2020-09-14T20:09:32.318Z] 20:09:32    ERROR -     self.start_binary(self.startup_timeout)
[task 2020-09-14T20:09:32.318Z] 20:09:32    ERROR - 
[task 2020-09-14T20:09:32.318Z] 20:09:32    ERROR -   File "z:\task_1600113784\build\venv\lib\site-packages\marionette_driver\marionette.py", line 493, in start_binary
[task 2020-09-14T20:09:32.318Z] 20:09:32    ERROR -     reraise(IOError, IOError(msg.format(timeout)), sys.exc_info()[2])
[task 2020-09-14T20:09:32.319Z] 20:09:32    ERROR - 
[task 2020-09-14T20:09:32.319Z] 20:09:32    ERROR -   File "z:\task_1600113784\build\venv\lib\site-packages\marionette_driver\marionette.py", line 485, in start_binary
[task 2020-09-14T20:09:32.319Z] 20:09:32    ERROR -     self.raise_for_port(timeout=timeout)
[task 2020-09-14T20:09:32.319Z] 20:09:32    ERROR - 
[task 2020-09-14T20:09:32.319Z] 20:09:32    ERROR -   File "z:\task_1600113784\build\venv\lib\site-packages\marionette_driver\marionette.py", line 573, in raise_for_port
[task 2020-09-14T20:09:32.319Z] 20:09:32    ERROR -     self.host, self.port))
[task 2020-09-14T20:09:32.319Z] 20:09:32    ERROR - 
[task 2020-09-14T20:09:32.319Z] 20:09:32    ERROR - IOError: Process killed after 120s because no connection to Marionette server could be established. Check gecko.log for errors
[task 2020-09-14T20:09:32.319Z] 20:09:32    ERROR - 
[task 2020-09-14T20:09:32.345Z] 20:09:32    ERROR - Return code: 1
[task 2020-09-14T20:09:32.346Z] 20:09:32    ERROR - No checks run.
[task 2020-09-14T20:09:32.346Z] 20:09:32    ERROR - No suite end message was emitted by this harness.
[task 2020-09-14T20:09:32.346Z] 20:09:32     INFO - AWSY exited with return code 1: FAILURE
[task 2020-09-14T20:09:32.346Z] 20:09:32    ERROR - # TBPL FAILURE #```

Firefox didn't startup correctly and the marionette-startup-requested notification is never fired.

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

Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=327689506&repo=autoland&lineNumber=969

[task 2021-01-25T12:52:15.359Z] 12:52:15 INFO - Using workspace for temporary data: "Z:\task_1611576732\build\tests\awsy"
[task 2021-01-25T12:52:15.359Z] 12:52:15 INFO - mozversion application_buildid: 20210125114750
[task 2021-01-25T12:52:15.360Z] 12:52:15 INFO - mozversion application_changeset: 31462a8e07406103e8d97b19faf9af3bd63530b6
[task 2021-01-25T12:52:15.360Z] 12:52:15 INFO - mozversion application_display_name: Firefox Nightly
[task 2021-01-25T12:52:15.360Z] 12:52:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
[task 2021-01-25T12:52:15.360Z] 12:52:15 INFO - mozversion application_name: Firefox
[task 2021-01-25T12:52:15.360Z] 12:52:15 INFO - mozversion application_remotingname: firefox
[task 2021-01-25T12:52:15.360Z] 12:52:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland
[task 2021-01-25T12:52:15.360Z] 12:52:15 INFO - mozversion application_vendor: Mozilla
[task 2021-01-25T12:52:15.360Z] 12:52:15 INFO - mozversion application_version: 86.0a1
[task 2021-01-25T12:52:15.361Z] 12:52:15 INFO - mozversion platform_buildid: 20210125114750
[task 2021-01-25T12:52:15.361Z] 12:52:15 INFO - mozversion platform_changeset: 31462a8e07406103e8d97b19faf9af3bd63530b6
[task 2021-01-25T12:52:15.361Z] 12:52:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland
[task 2021-01-25T12:52:15.361Z] 12:52:15 INFO - mozversion platform_version: 86.0a1
[task 2021-01-25T12:52:15.364Z] 12:52:15 INFO - Application command: Z:\task_1611576732\build\application\firefox\firefox.exe -no-remote -marionette --wait-for-browser -profile c:\users\task_1611576732\appdata\local\temp\tmpg5vdka.profile
[task 2021-01-25T12:54:15.686Z] 12:54:15 ERROR - Process killed after 120s because no connection to Marionette server could be established. Check gecko.log for errors
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR - Traceback (most recent call last):
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR -
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR - File "z:\task_1611576732\build\venv\lib\site-packages\marionette_harness\runtests.py", line 103, in cli
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR - failed = harness_instance.run()
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR -
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR - File "z:\task_1611576732\build\venv\lib\site-packages\marionette_harness\runtests.py", line 77, in run
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR - runner.run_tests(tests)
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR -
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR - File "z:\task_1611576732\build\venv\lib\site-packages\marionette_harness\runner\base.py", line 1011, in run_tests
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR - self.marionette = self.driverclass(**self._build_kwargs())
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR -
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR - File "z:\task_1611576732\build\venv\lib\site-packages\marionette_driver\marionette.py", line 486, in init
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR - self.start_binary(self.startup_timeout)
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR -
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR - File "z:\task_1611576732\build\venv\lib\site-packages\marionette_driver\marionette.py", line 516, in start_binary
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR - reraise(IOError, IOError(msg.format(timeout)), sys.exc_info()[2])
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR -
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR - File "z:\task_1611576732\build\venv\lib\site-packages\marionette_driver\marionette.py", line 506, in start_binary
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR - self.raise_for_port(timeout=timeout)
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR -
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR - File "z:\task_1611576732\build\venv\lib\site-packages\marionette_driver\marionette.py", line 598, in raise_for_port
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR - self.host, self.port
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR -
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR - IOError: Process killed after 120s because no connection to Marionette server could be established. Check gecko.log for errors
[task 2021-01-25T12:54:15.687Z] 12:54:15 ERROR -
[task 2021-01-25T12:54:15.711Z] 12:54:15 ERROR - Return code: 1
[task 2021-01-25T12:54:15.712Z] 12:54:15 ERROR - No checks run.
[task 2021-01-25T12:54:15.712Z] 12:54:15 ERROR - No suite end message was emitted by this harness.
[task 2021-01-25T12:54:15.712Z] 12:54:15 INFO - AWSY exited with return code 1: FAILURE
[task 2021-01-25T12:54:15.712Z] 12:54:15 ERROR - # TBPL FAILURE #
[task 2021-01-25T12:54:15.712Z] 12:54:15 WARNING - setting return code to 2

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

Status: REOPENED → RESOLVED
Closed: 4 years ago → 4 years ago
Resolution: --- → INCOMPLETE

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=343727209&repo=autoland&lineNumber=1642

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

Status: REOPENED → RESOLVED
Closed: 4 years ago → 4 years ago
Resolution: --- → INCOMPLETE

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=351946198&repo=mozilla-central&lineNumber=854

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

Status: REOPENED → RESOLVED
Closed: 4 years ago → 3 years ago
Resolution: --- → INCOMPLETE

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=355936600&repo=autoland

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

Status: REOPENED → RESOLVED
Closed: 3 years ago → 3 years ago
Resolution: --- → INCOMPLETE

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=387878657&repo=try

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Depends on: 1784591
No longer depends on: 1382162

There should be no more failures here with the causing patch backed out and bug 1784591 fixed. In case of similar issues coming up in the future lets get a new bug opened then.

Status: REOPENED → RESOLVED
Closed: 3 years ago → 2 years ago
status-firefox106: --- → fixed
Resolution: --- → FIXED
Target Milestone: --- → 106 Branch
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.