Closed Bug 1567921 Opened 5 years ago Closed 5 years ago

Perma gtest | timed out after 300 seconds without output

Categories

(Core :: Networking, defect, P3)

Product:
Core
Component:
Networking
Type:
defect

Tracking

()

Status:
RESOLVED WORKSFORME

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [necko-triaged])

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=257681107&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/YV94XeCsSAifs6PhkEOPQw/runs/0/artifacts/public/logs/live_backing.log

13:19:41 INFO - TEST-PASS | TestMozURL.Path | test completed (time: 0ms)
13:19:41 INFO - TEST-START | TestMozURL.HostPort
13:19:41 INFO - TEST-PASS | TestMozURL.HostPort | test completed (time: 1ms)
13:19:41 INFO - TEST-START | TestMozURL.Origin
13:19:41 INFO - TEST-PASS | TestMozURL.Origin | test completed (time: 0ms)
13:19:41 INFO - TEST-START | TestMozURL.BaseDomain
13:19:41 INFO - TEST-PASS | TestMozURL.BaseDomain | test completed (time: 0ms)
13:19:41 INFO - TEST-START | TestMozURL.UrlTestData
13:19:41 INFO - TEST-PASS | TestMozURL.UrlTestData | test completed (time: 11ms)
13:19:41 INFO - TEST-START | TestPACMan.TestCreateDHCPClientAndGetOption
13:19:41 INFO - TEST-PASS | TestPACMan.TestCreateDHCPClientAndGetOption | test completed (time: 0ms)
13:19:41 INFO - TEST-START | TestPACMan.TestCreateDHCPClientAndGetEmptyOption
13:19:41 INFO - TEST-PASS | TestPACMan.TestCreateDHCPClientAndGetEmptyOption | test completed (time: 0ms)
13:19:41 INFO - TEST-START | TestPACMan.WhenTheDHCPClientExistsAndDHCPIsNonEmptyDHCPOptionIsUsedAsPACUri
13:19:41 INFO - TEST-PASS | TestPACMan.WhenTheDHCPClientExistsAndDHCPIsNonEmptyDHCPOptionIsUsedAsPACUri | test completed (time: 4ms)
13:19:41 INFO - TEST-START | TestPACMan.WhenTheDHCPResponseIsEmptyWPADDefaultsToStandardURL
13:24:42 ERROR - gtest TEST-UNEXPECTED-FAIL | gtest | timed out after 300 seconds without output
13:24:42 ERROR - Return code: 1
13:24:42 ERROR - No tests run or test summary not found
13:24:42 INFO - TinderboxPrint: gtest-gtest<br/><em class="testfail">T-FAIL</em>
13:24:42 ERROR - # TBPL FAILURE #
13:24:42 WARNING - setting return code to 2
13:24:42 ERROR - The gtest suite: gtest ran with return status: FAILURE
13:24:42 INFO - Running post-action listener: _package_coverage_data
13:24:42 INFO - Running post-action listener: _resource_record_post_action
13:24:42 INFO - Running post-action listener: process_java_coverage_data
13:24:42 INFO - [mozharness: 2019-07-22 13:24:42.185000Z] Finished run-tests step (success)
13:24:42 INFO - Running post-run listener: _resource_record_post_run
13:24:42 INFO - Validating Perfherder data against Z:\task_1563795231\mozharness\external_tools\performance-artifact-schema.json
13:24:42 INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"subtests": [{"name": "cpu_percent", "value": 9.053311258278141}, {"name": "io_write_bytes", "value": 553259520}, {"name": "io.read_bytes", "value": 21066752}, {"name": "io_write_time", "value": 10}, {"name": "io_read_time", "value": 1}], "extraOptions": ["taskcluster-c5.2xlarge"], "name": "gtest.gtest.gtest.overall"}, {"subtests": [{"name": "time", "value": 2.0879998207092285}, {"name": "cpu_percent", "value": 13.237499999999999}], "name": "gtest.gtest.gtest.install"}, {"subtests": [{"name": "time", "value": 0.11400008201599121}], "name": "gtest.gtest.gtest.stage-files"}, {"subtests": [{"name": "time", "value": 756.6089999675751}, {"name": "cpu_percent", "value": 9.047656249999996}], "name": "gtest.gtest.gtest.run-tests"}]}
13:24:42 INFO - Total resource usage - Wall time: 758s; CPU: 9.0%; Read bytes: 21066752; Write bytes: 553259520; Read time: 1; Write time: 10
13:24:42 INFO - TinderboxPrint: CPU usage<br/>9.0%
13:24:42 INFO - TinderboxPrint: I/O read bytes / time<br/>21,066,752 / 1
13:24:42 INFO - TinderboxPrint: I/O write bytes / time<br/>553,259,520 / 10
13:24:42 INFO - TinderboxPrint: CPU idle<br/>5,520.6 (90.9%)
13:24:42 INFO - TinderboxPrint: CPU system<br/>349.2 (5.7%)
13:24:42 INFO - TinderboxPrint: CPU user<br/>197.5 (3.3%)
13:24:42 INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 61440; Write bytes: 16384; Read time: 0; Write time: 0
13:24:42 INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
13:24:42 INFO - run-tests - Wall time: 757s; CPU: 9.0%; Read bytes: 20808704; Write bytes: 502673920; Read time: 1; Write time: 10
13:24:42 WARNING - returning nonzero exit status 2
[taskcluster 2019-07-22T13:24:42.518Z] Exit Code: 2
[taskcluster 2019-07-22T13:24:42.518Z] User Time: 0s
[taskcluster 2019-07-22T13:24:42.518Z] Kernel Time: 0s
[taskcluster 2019-07-22T13:24:42.518Z] Wall Time: 16m26.4185155s
[taskcluster 2019-07-22T13:24:42.518Z] Result: FAILED

Regressed by: 1486042

Hi Nathan, please take a look at this bug.

Flags: needinfo?(nfroyd)

Nathan already replied to a needinfo in bug 1486042.

Flags: needinfo?(nfroyd)

P3 since only happen in a few days (July 19-22) and disappear.

Priority: P5 → P3
Whiteboard: [necko-triaged]

I think this was fixed by only enabling cross-language LTO for shippable builds.

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → WORKSFORME
Has Regression Range: --- → yes
You need to log in before you can comment on or make changes to this bug.