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
Description
•