Closed Bug 1872958 Opened 2 years ago Closed 1 year ago

Intermittent <something>/reftest.list | application timed out after 370 seconds with no output | followed by application terminated with exit code 3221225477 | single tracking bug

Categories

(Core :: Graphics: ImageLib, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, intermittent-testcase)

Filed by: imoraru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=442078532&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/L3vWIhriRPmlUsn4UH8iTQ/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/L3vWIhriRPmlUsn4UH8iTQ/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1


[task 2024-01-04T00:13:45.071Z] 00:13:45     INFO - REFTEST TEST-PASS | image/test/reftest/ico/ico-png/transparent-png.ico == image/test/reftest/ico/ico-png/transparent-png.png | image comparison, max difference: 0, number of differing pixels: 0
[task 2024-01-04T00:13:45.072Z] 00:13:45     INFO - REFTEST TEST-END | image/test/reftest/ico/ico-png/transparent-png.ico == image/test/reftest/ico/ico-png/transparent-png.png
[task 2024-01-04T00:13:45.079Z] 00:13:45     INFO - REFTEST INFO | Slowest test took 517ms (file:///Z:/task_170432323946152/build/tests/reftest/tests/image/test/reftest/ico/ico-png/ico-size-1x1-png.ico)
[task 2024-01-04T00:13:45.079Z] 00:13:45     INFO - REFTEST INFO | Total canvas count = 2
[task 2024-01-04T00:13:45.729Z] 00:13:45     INFO - 1704327225728	Marionette	TRACE	Received observer notification quit-application
[task 2024-01-04T00:13:45.730Z] 00:13:45     INFO - 1704327225729	Marionette	INFO	Stopped listening on port 2828
[task 2024-01-04T00:13:45.730Z] 00:13:45     INFO - 1704327225729	Marionette	DEBUG	Marionette stopped listening
[task 2024-01-04T00:13:45.832Z] 00:13:45     INFO - console.error: ({})
[task 2024-01-04T00:13:46.287Z] 00:13:46     INFO - REFTEST INFO | Process mode: e10s
[task 2024-01-04T00:13:46.565Z] 00:13:46  WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2024-01-04T00:13:46.566Z] 00:13:46     INFO - REFTEST INFO | Running tests in file:///Z:/task_170432323946152/build/tests/reftest/tests/image/test/reftest/ico/ico-bmp-32bpp/reftest.list
[task 2024-01-04T00:13:46.590Z] 00:13:46     INFO - REFTEST INFO | Running with e10s: True
[task 2024-01-04T00:13:46.590Z] 00:13:46     INFO - REFTEST INFO | Running with fission: True
[task 2024-01-04T00:13:46.591Z] 00:13:46     INFO - REFTEST INFO | Application command: Z:\task_170432323946152\build\application\firefox\firefox.exe -marionette --wait-for-browser -profile C:\Users\task_170432323946152\AppData\Local\Temp\tmpj_su1kt7.mozrunner
[task 2024-01-04T00:13:47.090Z] 00:13:47     INFO - 1704327227090	Marionette	INFO	Marionette enabled
[task 2024-01-04T00:13:47.093Z] 00:13:47     INFO - 1704327227092	Marionette	TRACE	Received observer notification final-ui-startup
[task 2024-01-04T00:13:47.169Z] 00:13:47     INFO - 1704327227169	Marionette	INFO	Listening on port 2828
[task 2024-01-04T00:13:47.172Z] 00:13:47     INFO - 1704327227171	Marionette	DEBUG	Marionette is listening
[task 2024-01-04T00:13:47.185Z] 00:13:47     INFO - 1704327227184	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:50667
[task 2024-01-04T00:13:47.212Z] 00:13:47     INFO - 1704327227211	Marionette	DEBUG	Closed connection 0
[task 2024-01-04T00:13:47.212Z] 00:13:47     INFO - 1704327227211	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:50670
[task 2024-01-04T00:13:47.689Z] 00:13:47     INFO - 1704327227688	Marionette	DEBUG	1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2024-01-04T00:13:47.693Z] 00:13:47     INFO - 1704327227692	Marionette	DEBUG	Waiting for initial application window
[task 2024-01-04T00:13:47.806Z] 00:13:47     INFO - [ERROR firefox_on_glean::private::string] Unable to set string metric in non-main process. Ignoring.
[task 2024-01-04T00:13:48.104Z] 00:13:48     INFO - JavaScript error: resource://gre/modules/XULStore.sys.mjs, line 60: Error: Can't find profile directory.
[task 2024-01-04T00:13:48.172Z] 00:13:48     INFO - [ERROR firefox_on_glean::private::string] Unable to set string metric in non-main process. Ignoring.
[task 2024-01-04T00:13:48.284Z] 00:13:48     INFO - [ERROR firefox_on_glean::private::string] Unable to set string metric in non-main process. Ignoring.
[task 2024-01-04T00:13:49.075Z] 00:13:49     INFO - [ERROR firefox_on_glean::private::string] Unable to set string metric in non-main process. Ignoring.
[task 2024-01-04T00:13:49.096Z] 00:13:49     INFO - [ERROR firefox_on_glean::private::string] Unable to set string metric in non-main process. Ignoring.
[task 2024-01-04T00:13:49.115Z] 00:13:49     INFO - [ERROR firefox_on_glean::private::string] Unable to set string metric in non-main process. Ignoring.
[task 2024-01-04T00:19:59.415Z] 00:19:59    ERROR - REFTEST ERROR | file:///Z:/task_170432323946152/build/tests/reftest/tests/image/test/reftest/ico/ico-png/reftest.list | application timed out after 370 seconds with no output
[task 2024-01-04T00:19:59.417Z] 00:19:59  WARNING - REFTEST WARNING | Force-terminating active process(es).
[task 2024-01-04T00:19:59.417Z] 00:19:59     INFO - REFTEST TEST-INFO | started process screenshot
[task 2024-01-04T00:19:59.627Z] 00:19:59     INFO - REFTEST TEST-INFO | screenshot: exit 0
[task 2024-01-04T00:19:59.641Z] 00:19:59     INFO -  TEST-INFO | crashinject: exit 0
[task 2024-01-04T00:19:59.772Z] 00:19:59     INFO - REFTEST TEST-UNEXPECTED-FAIL | file:///Z:/task_170432323946152/build/tests/reftest/tests/image/test/reftest/ico/ico-png/reftest.list | application terminated with exit code 3221225477
[task 2024-01-04T00:19:59.823Z] 00:19:59     INFO -  Traceback (most recent call last):
[task 2024-01-04T00:19:59.823Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\tests\reftest\runreftest.py", line 1188, in <module>
[task 2024-01-04T00:19:59.823Z] 00:19:59     INFO -      sys.exit(run_test_harness(parser, options))
[task 2024-01-04T00:19:59.824Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\tests\reftest\runreftest.py", line 1180, in run_test_harness
[task 2024-01-04T00:19:59.824Z] 00:19:59     INFO -      result = reftest.runTests(options.tests, options)
[task 2024-01-04T00:19:59.824Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\tests\reftest\runreftest.py", line 706, in runTests
[task 2024-01-04T00:19:59.824Z] 00:19:59     INFO -      return self.runSerialTests(manifests, options, cmdargs)
[task 2024-01-04T00:19:59.825Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\tests\reftest\runreftest.py", line 1122, in runSerialTests
[task 2024-01-04T00:19:59.825Z] 00:19:59     INFO -      status = run(tests=tests)
[task 2024-01-04T00:19:59.825Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\tests\reftest\runreftest.py", line 1071, in run
[task 2024-01-04T00:19:59.826Z] 00:19:59     INFO -      status = self.runApp(
[task 2024-01-04T00:19:59.826Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\tests\reftest\runreftest.py", line 1017, in runApp
[task 2024-01-04T00:19:59.827Z] 00:19:59     INFO -      raise reraise(exc, value, tb)
[task 2024-01-04T00:19:59.827Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\venv\lib\site-packages\six.py", line 696, in reraise
[task 2024-01-04T00:19:59.827Z] 00:19:59     INFO -      raise value
[task 2024-01-04T00:19:59.827Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\tests\reftest\runreftest.py", line 956, in runApp
[task 2024-01-04T00:19:59.828Z] 00:19:59     INFO -      marionette.start_session()
[task 2024-01-04T00:19:59.828Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\venv\lib\site-packages\marionette_driver\decorators.py", line 34, in _
[task 2024-01-04T00:19:59.828Z] 00:19:59     INFO -      m._handle_socket_failure()
[task 2024-01-04T00:19:59.829Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\venv\lib\site-packages\marionette_driver\marionette.py", line 820, in _handle_socket_failure
[task 2024-01-04T00:19:59.829Z] 00:19:59     INFO -      reraise(exc_cls, exc, tb)
[task 2024-01-04T00:19:59.829Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\venv\lib\site-packages\six.py", line 696, in reraise
[task 2024-01-04T00:19:59.829Z] 00:19:59     INFO -      raise value
[task 2024-01-04T00:19:59.830Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\venv\lib\site-packages\marionette_driver\decorators.py", line 24, in _
[task 2024-01-04T00:19:59.830Z] 00:19:59     INFO -      return func(*args, **kwargs)
[task 2024-01-04T00:19:59.830Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1371, in start_session
[task 2024-01-04T00:19:59.830Z] 00:19:59     INFO -      resp = self._send_message("WebDriver:NewSession", capabilities)
[task 2024-01-04T00:19:59.831Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\venv\lib\site-packages\marionette_driver\decorators.py", line 34, in _
[task 2024-01-04T00:19:59.831Z] 00:19:59     INFO -      m._handle_socket_failure()
[task 2024-01-04T00:19:59.831Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\venv\lib\site-packages\marionette_driver\marionette.py", line 820, in _handle_socket_failure
[task 2024-01-04T00:19:59.831Z] 00:19:59     INFO -      reraise(exc_cls, exc, tb)
[task 2024-01-04T00:19:59.832Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\venv\lib\site-packages\six.py", line 696, in reraise
[task 2024-01-04T00:19:59.832Z] 00:19:59     INFO -      raise value
[task 2024-01-04T00:19:59.832Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\venv\lib\site-packages\marionette_driver\decorators.py", line 24, in _
[task 2024-01-04T00:19:59.832Z] 00:19:59     INFO -      return func(*args, **kwargs)
[task 2024-01-04T00:19:59.833Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\venv\lib\site-packages\marionette_driver\marionette.py", line 769, in _send_message
[task 2024-01-04T00:19:59.834Z] 00:19:59     INFO -      msg = self.client.request(name, params)
[task 2024-01-04T00:19:59.834Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\venv\lib\site-packages\marionette_driver\transport.py", line 382, in request
[task 2024-01-04T00:19:59.834Z] 00:19:59     INFO -      return self.receive()
[task 2024-01-04T00:19:59.834Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\venv\lib\site-packages\marionette_driver\transport.py", line 223, in receive
[task 2024-01-04T00:19:59.835Z] 00:19:59     INFO -      chunk = sock.recv(recv_bytes)
[task 2024-01-04T00:19:59.835Z] 00:19:59     INFO -  ConnectionResetError: [WinError 10054] An existing connection was forcibly closed by the remote host
[task 2024-01-04T00:19:59.857Z] 00:19:59     INFO -  Exception ignored in: <function BaseProfile.__del__ at 0x000002365FC171F0>
[task 2024-01-04T00:19:59.858Z] 00:19:59     INFO -  Traceback (most recent call last):
[task 2024-01-04T00:19:59.858Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\venv\lib\site-packages\mozprofile\profile.py", line 84, in __del__
[task 2024-01-04T00:19:59.858Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\venv\lib\site-packages\mozprofile\profile.py", line 294, in cleanup
[task 2024-01-04T00:19:59.858Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\venv\lib\site-packages\mozprofile\profile.py", line 92, in cleanup
[task 2024-01-04T00:19:59.859Z] 00:19:59     INFO -    File "Z:\task_170432323946152\build\venv\lib\site-packages\mozfile\mozfile.py", line 248, in remove
[task 2024-01-04T00:19:59.859Z] 00:19:59     INFO -  ImportError: sys.meta_path is None, Python is likely shutting down
[task 2024-01-04T00:19:59.889Z] 00:19:59     INFO - Return code: 1
[task 2024-01-04T00:19:59.890Z] 00:19:59    ERROR - No suite end message was emitted by this harness.
[task 2024-01-04T00:19:59.890Z] 00:19:59     INFO - TinderboxPrint: reftest-reftest<br/>8117/0/0
[task 2024-01-04T00:19:59.890Z] 00:19:59  WARNING - setting return code to 2
[task 2024-01-04T00:19:59.890Z] 00:19:59     INFO - The reftest suite: reftest ran with return status: FAILURE
Summary: Intermittent image/test/reftest/ico/ico-png/reftest.list | single tracking bug → Intermittent <something>/reftest.list | application timed out after 370 seconds with no output | followed by application terminated with exit code 3221225477 | single tracking bug
See Also: → 1901423
Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.