1659772 - Intermittent MinGW Process killed after 120s because no connection to Marionette server could be established. Check gecko.log for errors after Failed to delete GMP storage directory

Status: RESOLVED DUPLICATE of bug 1637799

(Testing :: Firefox UI Tests, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: apavel [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=313334625&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/TJzqms8ORYChbMTB-ddYmg/runs/0/artifacts/public/logs/live_backing.log

---

[task 2020-08-18T16:33:34.297Z] 16:33:34 INFO - Return code: 0
[task 2020-08-18T16:33:34.297Z] 16:33:34 INFO - Running post-action listener: _resource_record_post_action
[task 2020-08-18T16:33:34.297Z] 16:33:34 INFO - [mozharness: 2020-08-18 16:33:34.297000Z] Finished install step (success)
[task 2020-08-18T16:33:34.297Z] 16:33:34 INFO - [mozharness: 2020-08-18 16:33:34.297000Z] Running run-tests step.
[task 2020-08-18T16:33:34.297Z] 16:33:34 INFO - Running pre-action listener: _pre_run_tests
[task 2020-08-18T16:33:34.298Z] 16:33:34 INFO - Running pre-action listener: _resource_record_pre_action
[task 2020-08-18T16:33:34.298Z] 16:33:34 INFO - Running pre-action listener: _set_gcov_prefix
[task 2020-08-18T16:33:34.298Z] 16:33:34 INFO - Running main action method: run_tests
[task 2020-08-18T16:33:34.591Z] 16:33:34 INFO - Running command: ['Z:\task_1597768018\build\venv\Scripts\python', 'Z:\task_1597768018\build\venv\Lib\site-packages\firefox_ui_harness\cli_functional.py', '--binary', 'Z:\task_1597768018\build\application\firefox\firefox.exe', '--address', 'localhost:2828', '--server-root', 'Z:\task_1597768018\build\tests\firefox-ui\resources', '--workspace', 'Z:\task_1597768018\build', '--gecko-log=-', '--log-raw=-', '--log-html', 'Z:\task_1597768018\build\blobber_upload_dir\report.html', '--log-xunit', 'Z:\task_1597768018\build\blobber_upload_dir\report.xml', '-vv', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--symbols-path', u'https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/RhdvyGzDQLCPYDdcNgOo-w/artifacts/public/build/target.crashreporter-symbols.zip', '--tag', 'remote', 'Z:\task_1597768018\build\tests\firefox-ui\tests\testing\firefox-ui\tests\functional\manifest.ini'] in Z:\task_1597768018\build\tests\firefox-ui
[task 2020-08-18T16:33:34.591Z] 16:33:34 INFO - Copy/paste: Z:\task_1597768018\build\venv\Scripts\python Z:\task_1597768018\build\venv\Lib\site-packages\firefox_ui_harness\cli_functional.py --binary Z:\task_1597768018\build\application\firefox\firefox.exe --address localhost:2828 --server-root Z:\task_1597768018\build\tests\firefox-ui\resources --workspace Z:\task_1597768018\build --gecko-log=- --log-raw=- --log-html Z:\task_1597768018\build\blobber_upload_dir\report.html --log-xunit Z:\task_1597768018\build\blobber_upload_dir\report.xml -vv --setpref=media.peerconnection.mtransport_process=false --setpref=network.process.enabled=false --symbols-path https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/RhdvyGzDQLCPYDdcNgOo-w/artifacts/public/build/target.crashreporter-symbols.zip --tag remote Z:\task_1597768018\build\tests\firefox-ui\tests\testing\firefox-ui\tests\functional\manifest.ini
[task 2020-08-18T16:33:34.591Z] 16:33:34 INFO - Using env: (same as previous command)
[task 2020-08-18T16:33:34.591Z] 16:33:34 INFO - Calling ['Z:\task_1597768018\build\venv\Scripts\python', 'Z:\task_1597768018\build\venv\Lib\site-packages\firefox_ui_harness\cli_functional.py', '--binary', 'Z:\task_1597768018\build\application\firefox\firefox.exe', '--address', 'localhost:2828', '--server-root', 'Z:\task_1597768018\build\tests\firefox-ui\resources', '--workspace', 'Z:\task_1597768018\build', '--gecko-log=-', '--log-raw=-', '--log-html', 'Z:\task_1597768018\build\blobber_upload_dir\report.html', '--log-xunit', 'Z:\task_1597768018\build\blobber_upload_dir\report.xml', '-vv', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--symbols-path', u'https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/RhdvyGzDQLCPYDdcNgOo-w/artifacts/public/build/target.crashreporter-symbols.zip', '--tag', 'remote', 'Z:\task_1597768018\build\tests\firefox-ui\tests\testing\firefox-ui\tests\functional\manifest.ini'] with output_timeout 1000
[task 2020-08-18T16:33:34.914Z] 16:33:34 INFO - Using workspace for temporary data: "Z:\task_1597768018\build"
[task 2020-08-18T16:33:34.921Z] 16:33:34 INFO - Application command: Z:\task_1597768018\build\application\firefox\firefox.exe -no-remote -marionette --wait-for-browser -profile Z:\task_1597768018\build\tmpgsxct7.mozrunner
[task 2020-08-18T16:33:37.081Z] 16:33:37 INFO - [2020-08-18T16:33:37Z WARN rkv::backend::impl_safe::environment] load_ratio() is irrelevant for this storage backend.
[task 2020-08-18T16:33:37.308Z] 16:33:37 INFO - [4684, Main Thread] WARNING: Failed to acquire a DXGI adapter for enumerating outputs.: file /builds/worker/checkouts/gecko/gfx/thebes/DeviceManagerDx.cpp, line 146
[task 2020-08-18T16:33:37.308Z] 16:33:37 INFO - [4684, Main Thread] WARNING: Failed to acquire a DXGI adapter for checking hardware stretching support.: file /builds/worker/checkouts/gecko/gfx/thebes/DeviceManagerDx.cpp, line 207
[task 2020-08-18T16:33:37.308Z] 16:33:37 INFO - [4684, Main Thread] WARNING: DWM not enabled, falling back to software vsync: file /builds/worker/checkouts/gecko/gfx/thebes/gfxWindowsPlatform.cpp, line 1942
[task 2020-08-18T16:33:37.328Z] 16:33:37 INFO - [4684, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp, line 902
[task 2020-08-18T16:33:37.447Z] 16:33:37 INFO - 1597768417441 Marionette TRACE Marionette enabled
[task 2020-08-18T16:33:37.594Z] 16:33:37 INFO - 1597768417590 Marionette TRACE Received observer notification toplevel-window-ready
[task 2020-08-18T16:33:37.634Z] 16:33:37 INFO - [4684, Main Thread] WARNING: 'NS_FAILED(GetAccentColor(unused))', file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp, line 486
[task 2020-08-18T16:33:37.649Z] 16:33:37 INFO - [4684, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp, line 4248
[task 2020-08-18T16:33:37.649Z] 16:33:37 INFO - [4684, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp, line 4248
[task 2020-08-18T16:33:37.844Z] 16:33:37 INFO - [4684, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp, line 1131
[task 2020-08-18T16:33:38.459Z] 16:33:38 INFO - [4684, Main Thread] WARNING: NS_ENSURE_TRUE(presShell) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp, line 4246
[task 2020-08-18T16:33:38.514Z] 16:33:38 INFO - [Parent 4684, GMPThread] WARNING: Failed to delete GMP storage directory: file /builds/worker/checkouts/gecko/dom/media/gmp/GMPServiceParent.cpp, line 1561
[task 2020-08-18T16:35:35.140Z] 16:35:35 ERROR - Process killed after 120s because no connection to Marionette server could be established. Check gecko.log for errors
[task 2020-08-18T16:35:35.140Z] 16:35:35 ERROR - Traceback (most recent call last):
[task 2020-08-18T16:35:35.140Z] 16:35:35 ERROR -
[task 2020-08-18T16:35:35.140Z] 16:35:35 ERROR - File "Z:\task_1597768018\build\venv\lib\site-packages\marionette_harness\runtests.py", line 91, in cli
[task 2020-08-18T16:35:35.140Z] 16:35:35 ERROR - failed = harness_instance.run()
[task 2020-08-18T16:35:35.140Z] 16:35:35 ERROR -
[task 2020-08-18T16:35:35.140Z] 16:35:35 ERROR - File "Z:\task_1597768018\build\venv\lib\site-packages\marionette_harness\runtests.py", line 71, in run
[task 2020-08-18T16:35:35.140Z] 16:35:35 ERROR - runner.run_tests(tests)
[task 2020-08-18T16:35:35.140Z] 16:35:35 ERROR -
[task 2020-08-18T16:35:35.140Z] 16:35:35 ERROR - File "Z:\task_1597768018\build\venv\lib\site-packages\marionette_harness\runner\base.py", line 850, in run_tests
[task 2020-08-18T16:35:35.140Z] 16:35:35 ERROR - self.marionette = self.driverclass(**self._build_kwargs())
[task 2020-08-18T16:35:35.140Z] 16:35:35 ERROR -
[task 2020-08-18T16:35:35.141Z] 16:35:35 ERROR - File "Z:\task_1597768018\build\venv\lib\site-packages\marionette_driver\marionette.py", line 465, in init
[task 2020-08-18T16:35:35.141Z] 16:35:35 ERROR - self.start_binary(self.startup_timeout)
[task 2020-08-18T16:35:35.141Z] 16:35:35 ERROR -
[task 2020-08-18T16:35:35.141Z] 16:35:35 ERROR - File "Z:\task_1597768018\build\venv\lib\site-packages\marionette_driver\marionette.py", line 493, in start_binary
[task 2020-08-18T16:35:35.141Z] 16:35:35 ERROR - reraise(IOError, IOError(msg.format(timeout)), sys.exc_info()[2])
[task 2020-08-18T16:35:35.141Z] 16:35:35 ERROR -
[task 2020-08-18T16:35:35.141Z] 16:35:35 ERROR - File "Z:\task_1597768018\build\venv\lib\site-packages\marionette_driver\marionette.py", line 485, in start_binary
[task 2020-08-18T16:35:35.141Z] 16:35:35 ERROR - self.raise_for_port(timeout=timeout)
[task 2020-08-18T16:35:35.141Z] 16:35:35 ERROR -
[task 2020-08-18T16:35:35.141Z] 16:35:35 ERROR - File "Z:\task_1597768018\build\venv\lib\site-packages\marionette_driver\marionette.py", line 573, in raise_for_port
[task 2020-08-18T16:35:35.141Z] 16:35:35 ERROR - self.host, self.port))
[task 2020-08-18T16:35:35.141Z] 16:35:35 ERROR -
[task 2020-08-18T16:35:35.141Z] 16:35:35 ERROR - IOError: Process killed after 120s because no connection to Marionette server could be established. Check gecko.log for errors
[task 2020-08-18T16:35:35.141Z] 16:35:35 ERROR -
[task 2020-08-18T16:35:35.208Z] 16:35:35 ERROR - Return code: 1
[task 2020-08-18T16:35:35.209Z] 16:35:35 ERROR - No checks run.
[task 2020-08-18T16:35:35.209Z] 16:35:35 ERROR - No suite end message was emitted by this harness.

Comment 1

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

This misses the marionette-startup-requested observer notification.

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 3

Platform breakdown:

• windows7-32-mingwclang: 3

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