1676059 - Intermittent gtest | timed out after 300 seconds without output

Status: RESOLVED DUPLICATE of bug 1115253

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

Description

[Treeherder Bug Filer]

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

---

[task 2020-11-08T22:00:28.883Z] 22:00:28     INFO -  TEST-START | ImageDecoders.AVIFStackCheck
[task 2020-11-08T22:05:28.941Z] 22:05:28     INFO -  gtest INFO | gtest | process wait complete, returncode=572
[task 2020-11-08T22:05:28.941Z] 22:05:28  WARNING -  gtest TEST-UNEXPECTED-FAIL | gtest | timed out after 300 seconds without output
[task 2020-11-08T22:05:28.941Z] 22:05:28     INFO -  gtest INFO | rungtests.py exits with code 1
[task 2020-11-08T22:05:28.998Z] 22:05:28    ERROR - Return code: 1
[task 2020-11-08T22:05:28.998Z] 22:05:28    ERROR - No tests run or test summary not found
[task 2020-11-08T22:05:28.998Z] 22:05:28     INFO - TinderboxPrint: gtest-gtest<br/><em class="testfail">T-FAIL</em>
[task 2020-11-08T22:05:28.998Z] 22:05:28  WARNING - # TBPL FAILURE #
[task 2020-11-08T22:05:28.998Z] 22:05:28  WARNING - setting return code to 2
[task 2020-11-08T22:05:28.999Z] 22:05:28  WARNING - The gtest suite: gtest ran with return status: FAILURE
[task 2020-11-08T22:05:28.999Z] 22:05:28     INFO - Running post-action listener: _package_coverage_data
[task 2020-11-08T22:05:28.999Z] 22:05:28     INFO - Running post-action listener: _resource_record_post_action
[task 2020-11-08T22:05:28.999Z] 22:05:28     INFO - Running post-action listener: process_java_coverage_data
[task 2020-11-08T22:05:28.999Z] 22:05:28     INFO - [mozharness: 2020-11-08 22:05:28.999000Z] Finished run-tests step (success)
[task 2020-11-08T22:05:28.999Z] 22:05:28     INFO - Running post-run listener: _resource_record_post_run
[task 2020-11-08T22:05:29.107Z] 22:05:29     INFO - instance_metadata.json not found; unable to determine instance type
[task 2020-11-08T22:05:29.109Z] 22:05:29     INFO - Validating Perfherder data against C:\Users\task_1604866901\mozharness\external_tools\performance-artifact-schema.json
[task 2020-11-08T22:05:29.112Z] 22:05:29     INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"subtests": [{"name": "cpu_percent", "value": 12.214739413680773}, {"name": "io_write_bytes", "value": 557944832}, {"name": "io.read_bytes", "value": 3990528}, {"name": "io_write_time", "value": 2}, {"name": "io_read_time", "value": 0}], "extraOptions": ["buildbot-unknown"], "name": "gtest.gtest.overall"}, {"subtests": [{"name": "time", "value": 0.0}], "name": "gtest.gtest.start-pulseaudio"}, {"subtests": [{"name": "time", "value": 2.42900013923645}, {"name": "cpu_percent", "value": 12.768749999999999}], "name": "gtest.gtest.install"}, {"subtests": [{"name": "time", "value": 0.17400002479553223}], "name": "gtest.gtest.stage-files"}, {"subtests": [{"name": "time", "value": 615.0910000801086}, {"name": "cpu_percent", "value": 12.21786006546644}], "name": "gtest.gtest.run-tests"}]}
[task 2020-11-08T22:05:29.112Z] 22:05:29     INFO - Total resource usage - Wall time: 617s; CPU: 12.0%; Read bytes: 3990528; Write bytes: 557944832; Read time: 0; Write time: 2
[task 2020-11-08T22:05:29.112Z] 22:05:29     INFO - TinderboxPrint: CPU usage<br/>12.2%
[task 2020-11-08T22:05:29.112Z] 22:05:29     INFO - TinderboxPrint: I/O read bytes / time<br/>3,990,528 / 0
[task 2020-11-08T22:05:29.112Z] 22:05:29     INFO - TinderboxPrint: I/O write bytes / time<br/>557,944,832 / 2
[task 2020-11-08T22:05:29.113Z] 22:05:29     INFO - TinderboxPrint: CPU idle<br/>4,332.5 (87.8%)
[task 2020-11-08T22:05:29.113Z] 22:05:29     INFO - TinderboxPrint: CPU user<br/>555.5 (11.3%)
[task 2020-11-08T22:05:29.113Z] 22:05:29     INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2020-11-08T22:05:29.114Z] 22:05:29     INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 65536; Write bytes: 24215552; Read time: 0; Write time: 0
[task 2020-11-08T22:05:29.114Z] 22:05:29     INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2020-11-08T22:05:29.120Z] 22:05:29     INFO - run-tests - Wall time: 615s; CPU: 12.0%; Read bytes: 3666944; Write bytes: 533721088; Read time: 0; Write time: 2
[task 2020-11-08T22:05:29.237Z] 22:05:29  WARNING - returning nonzero exit status 2
[taskcluster 2020-11-08T22:05:29.270Z]    Exit Code: 2
[taskcluster 2020-11-08T22:05:29.270Z]    User Time: 0s
[taskcluster 2020-11-08T22:05:29.270Z]  Kernel Time: 15.625ms
[taskcluster 2020-11-08T22:05:29.270Z]    Wall Time: 12m1.2476006s
[taskcluster 2020-11-08T22:05:29.270Z]       Result: FAILED
[taskcluster 2020-11-08T22:05:29.270Z] === Task Finished ===
[taskcluster 2020-11-08T22:05:29.270Z] Task Duration: 12m1.2476006s
[taskcluster 2020-11-08T22:05:29.358Z] Uploading artifact public/logs/localconfig.json from file logs\localconfig.json with content encoding "gzip", mime type "application/octet-stream" and expiry 2021-11-08T21:33:16.639Z
[taskcluster 2020-11-08T22:05:29.546Z] Uploading artifact public/test_info/resource-usage.json from file build\blobber_upload_dir\resource-usage.json with content encoding "gzip", mime type "application/octet-stream" and expiry 2021-11-08T21:33:16.639Z
[taskcluster 2020-11-08T22:05:29.774Z] Uploading redirect artifact public/logs/live.log to URL https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/bxdNhIMpQbKx9DhXHNzkhw/runs/0/artifacts/public/logs/live_backing.log with mime type "text/plain; charset=utf-8" and expiry 2021-11-08T21:33:16.639Z
[taskcluster:error] exit status 2```

Comment 1

[Intermittent Failures Robot]

1 failures in 4128 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• windows10-64: 1

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

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 5
• mozilla-central: 1

Platform breakdown:

• windows10-64: 6

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676059&startday=2020-11-09&endday=2020-11-15&tree=all