Closed
Bug 1904549
Opened 2 years ago
Closed 1 year ago
Intermittent css-display/reftest.list | application terminated with exit code 572
Categories
(Core :: Graphics: WebRender, defect, P5)
Core
Graphics: WebRender
Tracking
()
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: sstanca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=463904038&repo=mozilla-beta
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/PW-fTWwSS9e5-LC99zolUQ/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/PW-fTWwSS9e5-LC99zolUQ/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1
[task 2024-06-25T10:34:24.852Z] 10:34:24 INFO - REFTEST TEST-PASS | layout/reftests/css-display/display-contents-suppression-dynamic.html == layout/reftests/css-display/display-contents-suppression-dynamic-ref.html | image comparison, max difference: 0, number of differing pixels: 0
[task 2024-06-25T10:34:24.853Z] 10:34:24 INFO - REFTEST TEST-END | layout/reftests/css-display/display-contents-suppression-dynamic.html == layout/reftests/css-display/display-contents-suppression-dynamic-ref.html
[task 2024-06-25T10:34:24.891Z] 10:34:24 INFO - REFTEST INFO | Slowest test took 433ms (file:///D:/task_171930940386136/build/tests/reftest/tests/layout/reftests/css-display/display-contents-state-change.html)
[task 2024-06-25T10:34:24.891Z] 10:34:24 INFO - REFTEST INFO | Total canvas count = 2
[task 2024-06-25T10:34:25.838Z] 10:34:25 INFO - 1719311665838 Marionette TRACE Received observer notification quit-application
[task 2024-06-25T10:34:25.839Z] 10:34:25 INFO - 1719311665838 Marionette INFO Stopped listening on port 2828
[task 2024-06-25T10:34:25.840Z] 10:34:25 INFO - 1719311665840 Marionette DEBUG Marionette stopped listening
[task 2024-06-25T10:34:28.360Z] 10:34:28 INFO - REFTEST INFO | Process mode: e10s
[task 2024-06-25T10:34:28.361Z] 10:34:28 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2024-06-25T10:34:28.362Z] 10:34:28 INFO - REFTEST INFO | Running tests in file:///D:/task_171930940386136/build/tests/reftest/tests/layout/reftests/css-required/reftest.list
[task 2024-06-25T10:34:28.383Z] 10:34:28 INFO - REFTEST INFO | Running with e10s: True
[task 2024-06-25T10:34:28.383Z] 10:34:28 INFO - REFTEST INFO | Running with fission: True
[task 2024-06-25T10:34:28.383Z] 10:34:28 INFO - REFTEST INFO | INFO | runtests.py | ASan using symbolizer at D:\task_171930940386136\build\application\firefox\llvm-symbolizer.exe
[task 2024-06-25T10:34:28.532Z] 10:34:28 INFO - REFTEST INFO | Failed determine available memory, disabling ASan low-memory configuration
[task 2024-06-25T10:34:28.533Z] 10:34:28 INFO - REFTEST INFO | Application command: D:\task_171930940386136\build\application\firefox\firefox.exe -marionette --wait-for-browser -profile C:\Users\task_171930940386136\AppData\Local\Temp\tmp2262mz2i.mozrunner
[task 2024-06-25T10:34:30.464Z] 10:34:30 INFO - 1719311670463 Marionette INFO Marionette enabled
[task 2024-06-25T10:34:30.474Z] 10:34:30 INFO - 1719311670473 Marionette TRACE Received observer notification final-ui-startup
[task 2024-06-25T10:34:31.044Z] 10:34:31 INFO - 1719311671043 Marionette INFO Listening on port 2828
[task 2024-06-25T10:34:31.052Z] 10:34:31 INFO - 1719311671051 Marionette DEBUG Marionette is listening
[task 2024-06-25T10:34:31.138Z] 10:34:31 INFO - 1719311671138 Marionette DEBUG Accepted connection 0 from 127.0.0.1:51528
[task 2024-06-25T10:34:31.246Z] 10:34:31 INFO - 1719311671246 Marionette DEBUG Closed connection 0
[task 2024-06-25T10:34:31.260Z] 10:34:31 INFO - 1719311671260 Marionette DEBUG Accepted connection 1 from 127.0.0.1:51529
[task 2024-06-25T10:34:32.280Z] 10:34:32 INFO - 1719311672280 Marionette DEBUG 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2024-06-25T10:34:32.307Z] 10:34:32 INFO - 1719311672306 Marionette DEBUG Waiting for initial application window
[task 2024-06-25T10:40:42.314Z] 10:40:42 ERROR - REFTEST ERROR | file:///D:/task_171930940386136/build/tests/reftest/tests/layout/reftests/css-display/reftest.list | application timed out after 370 seconds with no output
[task 2024-06-25T10:40:42.314Z] 10:40:42 WARNING - REFTEST WARNING | Force-terminating active process(es).
[task 2024-06-25T10:40:42.315Z] 10:40:42 INFO - REFTEST TEST-INFO | started process screenshot
[task 2024-06-25T10:40:42.699Z] 10:40:42 INFO - REFTEST TEST-INFO | screenshot: exit 0
[task 2024-06-25T10:40:42.700Z] 10:40:42 INFO - REFTEST INFO | Can't trigger Breakpad, just killing process
[task 2024-06-25T10:40:42.883Z] 10:40:42 INFO - REFTEST TEST-UNEXPECTED-FAIL | file:///D:/task_171930940386136/build/tests/reftest/tests/layout/reftests/css-display/reftest.list | application terminated with exit code 572
[task 2024-06-25T10:40:42.927Z] 10:40:42 INFO - Traceback (most recent call last):
[task 2024-06-25T10:40:42.928Z] 10:40:42 INFO - File "D:\task_171930940386136\build\tests\reftest\runreftest.py", line 1193, in <module>
[task 2024-06-25T10:40:42.928Z] 10:40:42 INFO - sys.exit(run_test_harness(parser, options))
[task 2024-06-25T10:40:42.929Z] 10:40:42 INFO - ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[task 2024-06-25T10:40:42.929Z] 10:40:42 INFO - File "D:\task_171930940386136\build\tests\reftest\runreftest.py", line 1185, in run_test_harness
[task 2024-06-25T10:40:42.929Z] 10:40:42 INFO - result = reftest.runTests(options.tests, options)
[task 2024-06-25T10:40:42.929Z] 10:40:42 INFO - ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[task 2024-06-25T10:40:42.930Z] 10:40:42 INFO - File "D:\task_171930940386136\build\tests\reftest\runreftest.py", line 704, in runTests
[task 2024-06-25T10:40:42.930Z] 10:40:42 INFO - return self.runSerialTests(manifests, options, cmdargs)
[task 2024-06-25T10:40:42.930Z] 10:40:42 INFO - ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[task 2024-06-25T10:40:42.930Z] 10:40:42 INFO - File "D:\task_171930940386136\build\tests\reftest\runreftest.py", line 1127, in runSerialTests
[task 2024-06-25T10:40:42.930Z] 10:40:42 INFO - status = run(tests=tests)
[task 2024-06-25T10:40:42.930Z] 10:40:42 INFO - ^^^^^^^^^^^^^^^^
[task 2024-06-25T10:40:42.930Z] 10:40:42 INFO - File "D:\task_171930940386136\build\tests\reftest\runreftest.py", line 1069, in run
[task 2024-06-25T10:40:42.930Z] 10:40:42 INFO - status = self.runApp(
[task 2024-06-25T10:40:42.930Z] 10:40:42 INFO - ^^^^^^^^^^^^
[task 2024-06-25T10:40:42.933Z] 10:40:42 INFO - File "D:\task_171930940386136\build\tests\reftest\runreftest.py", line 1015, in runApp
[task 2024-06-25T10:40:42.933Z] 10:40:42 INFO - raise reraise(exc, value, tb)
[task 2024-06-25T10:40:42.933Z] 10:40:42 INFO - ^^^^^^^^^^^^^^^^^^^^^^^
[task 2024-06-25T10:40:42.933Z] 10:40:42 INFO - File "D:\task_171930940386136\build\venv\Lib\site-packages\six.py", line 696, in reraise
[task 2024-06-25T10:40:42.934Z] 10:40:42 INFO - raise value
[task 2024-06-25T10:40:42.934Z] 10:40:42 INFO - File "D:\task_171930940386136\build\tests\reftest\runreftest.py", line 954, in runApp
[task 2024-06-25T10:40:42.934Z] 10:40:42 INFO - marionette.start_session()
[task 2024-06-25T10:40:42.935Z] 10:40:42 INFO - File "D:\task_171930940386136\build\venv\Lib\site-packages\marionette_driver\decorators.py", line 34, in _
[task 2024-06-25T10:40:42.935Z] 10:40:42 INFO - m._handle_socket_failure()
[task 2024-06-25T10:40:42.936Z] 10:40:42 INFO - File "D:\task_171930940386136\build\venv\Lib\site-packages\marionette_driver\marionette.py", line 820, in _handle_socket_failure
[task 2024-06-25T10:40:42.936Z] 10:40:42 INFO - reraise(exc_cls, exc, tb)
[task 2024-06-25T10:40:42.936Z] 10:40:42 INFO - File "D:\task_171930940386136\build\venv\Lib\site-packages\six.py", line 696, in reraise
[task 2024-06-25T10:40:42.936Z] 10:40:42 INFO - raise value
[task 2024-06-25T10:40:42.937Z] 10:40:42 INFO - File "D:\task_171930940386136\build\venv\Lib\site-packages\marionette_driver\decorators.py", line 24, in _
[task 2024-06-25T10:40:42.937Z] 10:40:42 INFO - return func(*args, **kwargs)
[task 2024-06-25T10:40:42.937Z] 10:40:42 INFO - ^^^^^^^^^^^^^^^^^^^^^
[task 2024-06-25T10:40:42.938Z] 10:40:42 INFO - File "D:\task_171930940386136\build\venv\Lib\site-packages\marionette_driver\marionette.py", line 1374, in start_session
[task 2024-06-25T10:40:42.938Z] 10:40:42 INFO - resp = self._send_message("WebDriver:NewSession", capabilities)
[task 2024-06-25T10:40:42.938Z] 10:40:42 INFO - ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[task 2024-06-25T10:40:42.938Z] 10:40:42 INFO - File "D:\task_171930940386136\build\venv\Lib\site-packages\marionette_driver\decorators.py", line 34, in _
[task 2024-06-25T10:40:42.939Z] 10:40:42 INFO - m._handle_socket_failure()
[task 2024-06-25T10:40:42.940Z] 10:40:42 INFO - File "D:\task_171930940386136\build\venv\Lib\site-packages\marionette_driver\marionette.py", line 820, in _handle_socket_failure
[task 2024-06-25T10:40:42.940Z] 10:40:42 INFO - reraise(exc_cls, exc, tb)
[task 2024-06-25T10:40:42.940Z] 10:40:42 INFO - File "D:\task_171930940386136\build\venv\Lib\site-packages\six.py", line 696, in reraise
[task 2024-06-25T10:40:42.941Z] 10:40:42 INFO - raise value
[task 2024-06-25T10:40:42.941Z] 10:40:42 INFO - File "D:\task_171930940386136\build\venv\Lib\site-packages\marionette_driver\decorators.py", line 24, in _
[task 2024-06-25T10:40:42.941Z] 10:40:42 INFO - return func(*args, **kwargs)
[task 2024-06-25T10:40:42.942Z] 10:40:42 INFO - ^^^^^^^^^^^^^^^^^^^^^
[task 2024-06-25T10:40:42.942Z] 10:40:42 INFO - File "D:\task_171930940386136\build\venv\Lib\site-packages\marionette_driver\marionette.py", line 769, in _send_message
[task 2024-06-25T10:40:42.942Z] 10:40:42 INFO - msg = self.client.request(name, params)
[task 2024-06-25T10:40:42.942Z] 10:40:42 INFO - ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[task 2024-06-25T10:40:42.943Z] 10:40:42 INFO - File "D:\task_171930940386136\build\venv\Lib\site-packages\marionette_driver\transport.py", line 382, in request
[task 2024-06-25T10:40:42.943Z] 10:40:42 INFO - return self.receive()
[task 2024-06-25T10:40:42.943Z] 10:40:42 INFO - ^^^^^^^^^^^^^^
[task 2024-06-25T10:40:42.943Z] 10:40:42 INFO - File "D:\task_171930940386136\build\venv\Lib\site-packages\marionette_driver\transport.py", line 223, in receive
[task 2024-06-25T10:40:42.945Z] 10:40:42 INFO - chunk = sock.recv(recv_bytes)
[task 2024-06-25T10:40:42.945Z] 10:40:42 INFO - ^^^^^^^^^^^^^^^^^^^^^
[task 2024-06-25T10:40:42.945Z] 10:40:42 INFO - ConnectionResetError: [WinError 10054] An existing connection was forcibly closed by the remote host
[task 2024-06-25T10:40:42.987Z] 10:40:42 INFO - Exception ignored in: <function BaseProfile.__del__ at 0x00000206CF28A2A0>
[task 2024-06-25T10:40:42.988Z] 10:40:42 INFO - Traceback (most recent call last):
[task 2024-06-25T10:40:42.988Z] 10:40:42 INFO - File "D:\task_171930940386136\build\venv\Lib\site-packages\mozprofile\profile.py", line 84, in __del__
[task 2024-06-25T10:40:42.988Z] 10:40:42 INFO - File "D:\task_171930940386136\build\venv\Lib\site-packages\mozprofile\profile.py", line 294, in cleanup
[task 2024-06-25T10:40:42.988Z] 10:40:42 INFO - File "D:\task_171930940386136\build\venv\Lib\site-packages\mozprofile\profile.py", line 92, in cleanup
[task 2024-06-25T10:40:42.989Z] 10:40:42 INFO - File "D:\task_171930940386136\build\venv\Lib\site-packages\mozfile\mozfile.py", line 248, in remove
[task 2024-06-25T10:40:42.989Z] 10:40:42 INFO - ImportError: sys.meta_path is None, Python is likely shutting down
[task 2024-06-25T10:40:43.036Z] 10:40:43 INFO - Return code: 1
[task 2024-06-25T10:40:43.037Z] 10:40:43 ERROR - No suite end message was emitted by this harness.
[task 2024-06-25T10:40:43.038Z] 10:40:43 INFO - TinderboxPrint: reftest-reftest<br/>8058/0/0
[task 2024-06-25T10:40:43.038Z] 10:40:43 WARNING - setting return code to 2
[task 2024-06-25T10:40:43.038Z] 10:40:43 INFO - The reftest suite: reftest ran with return status: FAILURE
[task 2024-06-25T10:40:43.038Z] 10:40:43 INFO - Running post-action listener: _package_coverage_data
[task 2024-06-25T10:40:43.038Z] 10:40:43 INFO - Running post-action listener: _resource_record_post_action
[task 2024-06-25T10:40:43.038Z] 10:40:43 INFO - Running post-action listener: process_java_coverage_data
[task 2024-06-25T10:40:43.038Z] 10:40:43 INFO - [mozharness: 2024-06-25 10:40:43.038864Z] Finished run-tests step (success)
[task 2024-06-25T10:40:43.039Z] 10:40:43 INFO - [mozharness: 2024-06-25 10:40:43.038864Z] Running uninstall step.
[task 2024-06-25T10:40:43.039Z] 10:40:43 INFO - Running pre-action listener: _resource_record_pre_action
[task 2024-06-25T10:40:43.039Z] 10:40:43 INFO - Running main action method: uninstall
[task 2024-06-25T10:40:43.039Z] 10:40:43 INFO - Skipping uninstall for non-MSIX test
[task 2024-06-25T10:40:43.039Z] 10:40:43 INFO - Running post-action listener: _resource_record_post_action
[task 2024-06-25T10:40:43.039Z] 10:40:43 INFO - [mozharness: 2024-06-25 10:40:43.039396Z] Finished uninstall step (success)
[task 2024-06-25T10:40:43.039Z] 10:40:43 INFO - Running post-run listener: _resource_record_post_run
[task 2024-06-25T10:40:45.104Z] 10:40:45 INFO - instance_metadata.json not found; unable to determine instance type
[task 2024-06-25T10:40:45.232Z] 10:40:45 INFO - Validating Perfherder data against D:\task_171930940386136\mozharness\external_tools\performance-artifact-schema.json
[task 2024-06-25T10:40:45.235Z] 10:40:45 INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "reftest.reftest.1.overall", "extraOptions": ["e10s", "buildbot-unknown"], "subtests": [{"name": "cpu_percent", "value": 34.04122126308256}, {"name": "io_write_bytes", "value": 10152585216}, {"name": "io.read_bytes", "value": 8222484992}, {"name": "io_write_time", "value": 222}, {"name": "io_read_time", "value": 714}]}, {"name": "reftest.reftest.1.start-pulseaudio", "subtests": [{"name": "time", "value": 0.0}, {"name": "cpu_percent", "value": 0}]}, {"name": "reftest.reftest.1.install", "subtests": [{"name": "time", "value": 11.48399999999998}, {"name": "cpu_percent", "value": 29.220015576323988}]}, {"name": "reftest.reftest.1.stage-files", "subtests": [{"name": "time", "value": 0.0}, {"name": "cpu_percent", "value": 0}]}, {"name": "reftest.reftest.1.run-tests", "subtests": [{"name": "time", "value": 2288.063}, {"name": "cpu_percent", "value": 34.06486591760299}]}, {"name": "reftest.reftest.1.uninstall", "subtests": [{"name": "time", "value": 0.0}, {"name": "cpu_percent", "value": 0}]}]}
[task 2024-06-25T10:40:45.235Z] 10:40:45 INFO - Total resource usage - Wall time: 2301s; CPU: Can't collect data; Read bytes: 8222484992; Write bytes: 10152585216; Read time: 714; Write time: 222
[task 2024-06-25T10:40:45.235Z] 10:40:45 INFO - TinderboxPrint: I/O read bytes / time<br/>8,222,484,992 / 714
[task 2024-06-25T10:40:45.235Z] 10:40:45 INFO - TinderboxPrint: I/O write bytes / time<br/>10,152,585,216 / 222
[task 2024-06-25T10:40:45.235Z] 10:40:45 INFO - TinderboxPrint: CPU idle<br/>17,913.2 (64.6%)
[task 2024-06-25T10:40:45.235Z] 10:40:45 INFO - TinderboxPrint: CPU system<br/>3,434.2 (12.4%)
[task 2024-06-25T10:40:45.235Z] 10:40:45 INFO - TinderboxPrint: CPU user<br/>6,238.5 (22.5%)
[task 2024-06-25T10:40:45.248Z] 10:40:45 INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-06-25T10:40:45.263Z] 10:40:45 INFO - install - Wall time: 11s; CPU: 29%; Read bytes: 41047040; Write bytes: 621793792; Read time: 0; Write time: 8
[task 2024-06-25T10:40:45.275Z] 10:40:45 INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-06-25T10:40:45.561Z] 10:40:45 INFO - run-tests - Wall time: 2288s; CPU: 34%; Read bytes: 8181437952; Write bytes: 9530791424; Read time: 714; Write time: 214
[task 2024-06-25T10:40:45.591Z] 10:40:45 INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-06-25T10:40:56.709Z] 10:40:56 WARNING - returning nonzero exit status 2
[taskcluster 2024-06-25T10:40:57.245Z] Exit Code: 2
[taskcluster 2024-06-25T10:40:57.245Z] User Time: 0s
[taskcluster 2024-06-25T10:40:57.245Z] Kernel Time: 0s
[taskcluster 2024-06-25T10:40:57.245Z] Wall Time: 41m49.1865914s
[taskcluster 2024-06-25T10:40:57.245Z] Result: FAILED
[taskcluster 2024-06-25T10:40:57.245Z] === Task Finished ===
[taskcluster 2024-06-25T10:40:57.245Z] Task Duration: 41m49.1865914s
[taskcluster 2024-06-25T10:40:58.710Z] Uploading artifact public/logs/localconfig.json from file D:\task_171930940386136\logs\localconfig.json with content encoding "gzip", mime type "application/json" and expiry 2024-09-23T09:19:39.706Z
[taskcluster 2024-06-25T10:40:59.035Z] Uploading artifact public/test_info/mozilla-test-fail-screenshot_g4xzirwp.png from file D:\task_171930940386136\build\blobber_upload_dir\mozilla-test-fail-screenshot_g4xzirwp.png with content encoding "identity", mime type "image/png" and expiry 2024-09-23T09:19:39.706Z
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•1 year ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
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.
Description
•