Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=468940074&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/KFVjF_DiQvevMdjpNAJmBw/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/KFVjF_DiQvevMdjpNAJmBw/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1
[task 2024-08-02T06:29:27.735Z] 06:29:27 INFO - REFTEST TEST-START | image/test/crashtests/1885209-1.html
[task 2024-08-02T06:29:27.737Z] 06:29:27 INFO - REFTEST TEST-LOAD | file:///builds/worker/workspace/build/tests/reftest/tests/image/test/crashtests/1885209-1.html | 1535 / 4053 (37%)
[task 2024-08-02T06:29:28.454Z] 06:29:28 ERROR - A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down
[task 2024-08-02T06:29:29.105Z] 06:29:29 INFO - 1722580169104 Marionette TRACE Received observer notification quit-application
[task 2024-08-02T06:29:29.112Z] 06:29:29 INFO - 1722580169105 Marionette INFO Stopped listening on port 2828
[task 2024-08-02T06:29:29.113Z] 06:29:29 INFO - 1722580169106 Marionette DEBUG Marionette stopped listening
[task 2024-08-02T06:29:29.803Z] 06:29:29 INFO - [Parent 1018, IPC I/O Parent] WARNING: process 4177 exited on signal 11: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_posix.cc:335
[task 2024-08-02T06:29:29.929Z] 06:29:29 INFO - !!! error running onStopped callback: TypeError: callback is not a function
[task 2024-08-02T06:29:34.829Z] 06:29:34 INFO - REFTEST TEST-UNEXPECTED-FAIL | image/test/crashtests/1885209-1.html | application terminated with exit code 245
[task 2024-08-02T06:29:34.830Z] 06:29:34 INFO - mozlog.structuredlog: Failure calling log handler:
[task 2024-08-02T06:29:35.048Z] 06:29:35 INFO - Traceback (most recent call last):
[task 2024-08-02T06:29:35.048Z] 06:29:35 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/mozlog/structuredlog.py", line 327, in _handle_log
[task 2024-08-02T06:29:35.049Z] 06:29:35 INFO - handler(data)
[task 2024-08-02T06:29:35.050Z] 06:29:35 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/mozlog/handlers/base.py", line 74, in __call__
[task 2024-08-02T06:29:35.051Z] 06:29:35 INFO - formatted = self.formatter(data)
[task 2024-08-02T06:29:35.052Z] 06:29:35 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/mozlog/handlers/base.py", line 42, in __call__
[task 2024-08-02T06:29:35.052Z] 06:29:35 INFO - return self.inner(item)
[task 2024-08-02T06:29:35.052Z] 06:29:35 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/mozlog/formatters/errorsummary.py", line 31, in __call__
[task 2024-08-02T06:29:35.052Z] 06:29:35 INFO - rv = BaseFormatter.__call__(self, data)
[task 2024-08-02T06:29:35.052Z] 06:29:35 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/mozlog/reader.py", line 72, in __call__
[task 2024-08-02T06:29:35.052Z] 06:29:35 INFO - return handler(data)
[task 2024-08-02T06:29:35.052Z] 06:29:35 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/mozlog/formatters/errorsummary.py", line 161, in shutdown_failure
[task 2024-08-02T06:29:35.052Z] 06:29:35 INFO - data["group"] = [g for g in self.groups if item["group"].endswith(g)][0]
[task 2024-08-02T06:29:35.052Z] 06:29:35 INFO - IndexError: list index out of range
[task 2024-08-02T06:29:35.373Z] 06:29:35 INFO - REFTEST INFO | Process mode: e10s
[task 2024-08-02T06:29:35.725Z] 06:29:35 INFO - Return code: 245
[task 2024-08-02T06:29:35.831Z] 06:29:35 ERROR - No suite end message was emitted by this harness.
[task 2024-08-02T06:29:35.831Z] 06:29:35 INFO - TinderboxPrint: reftest-crashtest<br/>3061/0/0
[task 2024-08-02T06:29:35.832Z] 06:29:35 WARNING - setting return code to 2
[task 2024-08-02T06:29:35.833Z] 06:29:35 INFO - The reftest suite: crashtest ran with return status: FAILURE
[task 2024-08-02T06:29:35.834Z] 06:29:35 INFO - Running post-action listener: _package_coverage_data
[task 2024-08-02T06:29:35.834Z] 06:29:35 INFO - Running post-action listener: _resource_record_post_action
[task 2024-08-02T06:29:35.835Z] 06:29:35 INFO - Running post-action listener: process_java_coverage_data
[task 2024-08-02T06:29:35.836Z] 06:29:35 INFO - [mozharness: 2024-08-02 06:29:35.834272Z] Finished run-tests step (success)
[task 2024-08-02T06:29:35.836Z] 06:29:35 INFO - [mozharness: 2024-08-02 06:29:35.834611Z] Running uninstall step.
[task 2024-08-02T06:29:35.836Z] 06:29:35 INFO - Running pre-action listener: _resource_record_pre_action
[task 2024-08-02T06:29:35.836Z] 06:29:35 INFO - Running main action method: uninstall
[task 2024-08-02T06:29:35.836Z] 06:29:35 INFO - Skipping uninstall for non-MSIX test
[task 2024-08-02T06:29:35.836Z] 06:29:35 INFO - Running post-action listener: _resource_record_post_action
[task 2024-08-02T06:29:35.836Z] 06:29:35 INFO - [mozharness: 2024-08-02 06:29:35.835516Z] Finished uninstall step (success)
[task 2024-08-02T06:29:35.836Z] 06:29:35 INFO - Running post-run listener: _resource_record_post_run
[task 2024-08-02T06:29:37.094Z] 06:29:37 INFO - Validating Perfherder data against /builds/worker/workspace/mozharness/external_tools/performance-artifact-schema.json
[task 2024-08-02T06:29:37.098Z] 06:29:37 INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "reftest.crashtest.overall", "extraOptions": ["e10s", "taskcluster-projects/887720501152/machineTypes/n2-standard-2"], "subtests": [{"name": "cpu_percent", "value": 72.13195556703734}, {"name": "io_write_bytes", "value": 2028945408}, {"name": "io.read_bytes", "value": 542535680}, {"name": "io_write_time", "value": 1978868}, {"name": "io_read_time", "value": 178576}]}, {"name": "reftest.crashtest.start-pulseaudio", "subtests": [{"name": "time", "value": 0.03268643299998075}, {"name": "cpu_percent", "value": 0}]}, {"name": "reftest.crashtest.install", "subtests": [{"name": "time", "value": 69.69237323299996}, {"name": "cpu_percent", "value": 51.181178160919536}]}, {"name": "reftest.crashtest.stage-files", "subtests": [{"name": "time", "value": 0.0004963390000511936}, {"name": "cpu_percent", "value": 0}]}, {"name": "reftest.crashtest.run-tests", "subtests": [{"name": "time", "value": 704.383235428}, {"name": "cpu_percent", "value": 74.21648445264849}]}, {"name": "reftest.crashtest.uninstall", "subtests": [{"name": "time", "value": 0.0005550939999920956}, {"name": "cpu_percent", "value": 0}]}]}
[task 2024-08-02T06:29:37.099Z] 06:29:37 INFO - Total resource usage - Wall time: 774s; CPU: Can't collect data; Read bytes: 542535680; Write bytes: 2028945408; Read time: 178576; Write time: 1978868
[task 2024-08-02T06:29:37.100Z] 06:29:37 INFO - TinderboxPrint: I/O read bytes / time<br/>542,535,680 / 178,576
[task 2024-08-02T06:29:37.101Z] 06:29:37 INFO - TinderboxPrint: I/O write bytes / time<br/>2,028,945,408 / 1,978,868
[task 2024-08-02T06:29:37.102Z] 06:29:37 INFO - TinderboxPrint: CPU idle<br/>353.9 (23.0%)
[task 2024-08-02T06:29:37.103Z] 06:29:37 INFO - TinderboxPrint: CPU iowait<br/>75.5 (4.9%)
[task 2024-08-02T06:29:37.104Z] 06:29:37 INFO - TinderboxPrint: CPU system<br/>120.1 (7.8%)
[task 2024-08-02T06:29:37.104Z] 06:29:37 INFO - TinderboxPrint: CPU user<br/>989.7 (64.2%)
[task 2024-08-02T06:29:37.105Z] 06:29:37 INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2024-08-02T06:29:37.106Z] 06:29:37 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-08-02T06:29:37.109Z] 06:29:37 INFO - install - Wall time: 70s; CPU: 51%; Read bytes: 835584; Write bytes: 1272217600; Read time: 824; Write time: 1251652
[task 2024-08-02T06:29:37.111Z] 06:29:37 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-08-02T06:29:37.143Z] 06:29:37 INFO - run-tests - Wall time: 704s; CPU: 74%; Read bytes: 541032448; Write bytes: 756727808; Read time: 177460; Write time: 727216
[task 2024-08-02T06:29:37.148Z] 06:29:37 INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-08-02T06:29:38.510Z] 06:29:38 WARNING - returning nonzero exit status 2
Description
•