Closed Bug 1704402 Opened 4 years ago Closed 3 years ago

Intermittent netwerk/test/unit/test_trr_confirmation.js | test_connectivity_change - [test_connectivity_change : 310] 9 == 10

Categories

(Core :: Networking, defect, P5)

defect

Tracking

()

RESOLVED WORKSFORME

People

(Reporter: intermittent-bug-filer, Assigned: valentin)

References

(Blocks 1 open bug)

Details

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

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


[task 2021-04-11T22:13:12.945Z] 22:13:12     INFO -  TEST-PASS | netwerk/test/unit/test_trr_confirmation.js | test_connectivity_change - [test_connectivity_change : 303] Should be CONFIRM_TRYING_OK - 1 == 1
[task 2021-04-11T22:13:12.945Z] 22:13:12     INFO -  TEST-PASS | netwerk/test/unit/test_trr_confirmation.js | test_connectivity_change - [test_connectivity_change : 21] expected confirmation state - 2 == 2
[task 2021-04-11T22:13:12.945Z] 22:13:12  WARNING -  TEST-UNEXPECTED-FAIL | netwerk/test/unit/test_trr_confirmation.js | test_connectivity_change - [test_connectivity_change : 310] 9 == 10
[task 2021-04-11T22:13:12.945Z] 22:13:12     INFO -  /builds/worker/workspace/build/tests/xpcshell/tests/netwerk/test/unit/test_trr_confirmation.js:test_connectivity_change:310
[task 2021-04-11T22:13:12.945Z] 22:13:12     INFO -  /builds/worker/workspace/build/tests/xpcshell/head.js:_do_main:241
[task 2021-04-11T22:13:12.945Z] 22:13:12     INFO -  /builds/worker/workspace/build/tests/xpcshell/head.js:_execute_test:598
[task 2021-04-11T22:13:12.945Z] 22:13:12     INFO -  -e:null:1
[task 2021-04-11T22:13:12.945Z] 22:13:12     INFO -  exiting test
[task 2021-04-11T22:13:12.945Z] 22:13:12     INFO -  Unexpected exception NS_ERROR_ABORT:
[task 2021-04-11T22:13:12.945Z] 22:13:12     INFO -  _abort_failed_test@/builds/worker/workspace/build/tests/xpcshell/head.js:861:20
[task 2021-04-11T22:13:12.945Z] 22:13:12     INFO -  do_report_result@/builds/worker/workspace/build/tests/xpcshell/head.js:962:5
[task 2021-04-11T22:13:12.945Z] 22:13:12     INFO -  Assert<@/builds/worker/workspace/build/tests/xpcshell/head.js:75:21
[task 2021-04-11T22:13:12.945Z] 22:13:12     INFO -  proto.report@resource://testing-common/Assert.jsm:233:10
[task 2021-04-11T22:13:12.945Z] 22:13:12     INFO -  equal@resource://testing-common/Assert.jsm:275:8
[task 2021-04-11T22:13:12.946Z] 22:13:12     INFO -  test_connectivity_change@/builds/worker/workspace/build/tests/xpcshell/tests/netwerk/test/unit/test_trr_confirmation.js:310:8
[task 2021-04-11T22:13:12.946Z] 22:13:12     INFO -  _do_main@/builds/worker/workspace/build/tests/xpcshell/head.js:241:6
[task 2021-04-11T22:13:12.946Z] 22:13:12     INFO -  _execute_test@/builds/worker/workspace/build/tests/xpcshell/head.js:598:5
[task 2021-04-11T22:13:12.946Z] 22:13:12     INFO -  @-e:1:1
[task 2021-04-11T22:13:12.946Z] 22:13:12     INFO -  exiting test
[task 2021-04-11T22:13:12.946Z] 22:13:12     INFO -  <<<<<<<
[task 2021-04-11T22:13:12.946Z] 22:13:12     INFO -  TEST-START | netwerk/test/unit/test_trr_cancel.js
[task 2021-04-11T22:13:15.764Z] 22:13:15     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | took 2835ms
[task 2021-04-11T22:13:15.764Z] 22:13:15     INFO -  TEST-START | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_upgrade.js
[task 2021-04-11T22:13:17.436Z] 22:13:17     INFO -  TEST-PASS | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_upgrade.js | took 1671ms
[task 2021-04-11T22:13:17.440Z] 22:13:17     INFO -  Retrying tests that failed when run in parallel.
[task 2021-04-11T22:13:17.441Z] 22:13:17     INFO -  TEST-START | dom/push/test/xpcshell/test_unregister_invalid_json.js
[task 2021-04-11T22:13:19.245Z] 22:13:19     INFO -  TEST-PASS | dom/push/test/xpcshell/test_unregister_invalid_json.js | took 1802ms
[task 2021-04-11T22:13:19.248Z] 22:13:19     INFO -  INFO | Result summary:
[task 2021-04-11T22:13:19.248Z] 22:13:19     INFO -  INFO | Passed: 1166
[task 2021-04-11T22:13:19.248Z] 22:13:19  WARNING -  INFO | Failed: 1
[task 2021-04-11T22:13:19.249Z] 22:13:19  WARNING -  One or more unittests failed.
[task 2021-04-11T22:13:19.249Z] 22:13:19     INFO -  INFO | Todo: 0
[task 2021-04-11T22:13:19.249Z] 22:13:19     INFO -  INFO | Retried: 1
[task 2021-04-11T22:13:19.250Z] 22:13:19     INFO -  SUITE-END | took 1014s
[task 2021-04-11T22:13:19.250Z] 22:13:19     INFO -  Node moz-http2 server shutting down ...
[task 2021-04-11T22:13:19.251Z] 22:13:19     INFO -  Process stdout
[task 2021-04-11T22:13:19.252Z] 22:13:19     INFO -  forked process without handler sent: [object Object]
[task 2021-04-11T22:13:19.252Z] 22:13:19     INFO -  Process stderr
[task 2021-04-11T22:13:19.252Z] 22:13:19     INFO -  (node:2860) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 window_update listeners added. Use emitter.setMaxListeners() to increase limit
[task 2021-04-11T22:13:19.252Z] 22:13:19     INFO -  (node:2860) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 window_update listeners added. Use emitter.setMaxListeners() to increase limit
[task 2021-04-11T22:13:19.253Z] 22:13:19     INFO -  (node:2860) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 window_update listeners added. Use emitter.setMaxListeners() to increase limit
[task 2021-04-11T22:13:19.253Z] 22:13:19     INFO -  (node:2860) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 window_update listeners added. Use emitter.setMaxListeners() to increase limit
[task 2021-04-11T22:13:19.332Z] 22:13:19    ERROR - Return code: 1
[task 2021-04-11T22:13:19.332Z] 22:13:19     INFO - TinderboxPrint: xpcshell-xpcshell<br/>1166/<em class="testfail">1</em>/0
[task 2021-04-11T22:13:19.333Z] 22:13:19  WARNING - # TBPL FAILURE #
[task 2021-04-11T22:13:19.333Z] 22:13:19  WARNING - setting return code to 2
[task 2021-04-11T22:13:19.333Z] 22:13:19  WARNING - The xpcshell suite: xpcshell ran with return status: FAILURE
[task 2021-04-11T22:13:19.333Z] 22:13:19     INFO - Running post-action listener: _package_coverage_data
[task 2021-04-11T22:13:19.333Z] 22:13:19     INFO - Running post-action listener: _resource_record_post_action
[task 2021-04-11T22:13:19.333Z] 22:13:19     INFO - Running post-action listener: process_java_coverage_data
[task 2021-04-11T22:13:19.334Z] 22:13:19     INFO - [mozharness: 2021-04-11 22:13:19.333990Z] Finished run-tests step (success)
[task 2021-04-11T22:13:19.334Z] 22:13:19     INFO - Running post-run listener: _resource_record_post_run
[task 2021-04-11T22:13:19.435Z] 22:13:19     INFO - Validating Perfherder data against /builds/worker/workspace/mozharness/external_tools/performance-artifact-schema.json
[task 2021-04-11T22:13:19.439Z] 22:13:19     INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"subtests": [{"name": "cpu_percent", "value": 91.39963735267449}, {"name": "io_write_bytes", "value": 11839627264}, {"name": "io.read_bytes", "value": 183672832}, {"name": "io_write_time", "value": 883464}, {"name": "io_read_time", "value": 2916}], "extraOptions": ["e10s", "taskcluster-m5.large"], "name": "xpcshell.xpcshell.overall"}, {"subtests": [{"name": "time", "value": 0.02048206329345703}], "name": "xpcshell.xpcshell.start-pulseaudio"}, {"subtests": [{"name": "time", "value": 50.35386085510254}, {"name": "cpu_percent", "value": 50.36938775510204}], "name": "xpcshell.xpcshell.install"}, {"subtests": [{"name": "time", "value": 0.01914191246032715}], "name": "xpcshell.xpcshell.stage-files"}, {"subtests": [{"name": "time", "value": 1054.126177072525}, {"name": "cpu_percent", "value": 93.38079847908742}], "name": "xpcshell.xpcshell.run-tests"}]}
[task 2021-04-11T22:13:19.440Z] 22:13:19     INFO - Total resource usage - Wall time: 1104s; CPU: 93.0%; Read bytes: 183672832; Write bytes: 11839627264; Read time: 2916; Write time: 883464
[task 2021-04-11T22:13:19.440Z] 22:13:19     INFO - TinderboxPrint: CPU usage<br/>93.4%
[task 2021-04-11T22:13:19.441Z] 22:13:19     INFO - TinderboxPrint: I/O read bytes / time<br/>183,672,832 / 2,916
[task 2021-04-11T22:13:19.441Z] 22:13:19     INFO - TinderboxPrint: I/O write bytes / time<br/>11,839,627,264 / 883,464
[task 2021-04-11T22:13:19.442Z] 22:13:19     INFO - TinderboxPrint: CPU idle<br/>180.0 (8.2%)
[task 2021-04-11T22:13:19.443Z] 22:13:19     INFO - TinderboxPrint: CPU system<br/>314.2 (14.2%)
[task 2021-04-11T22:13:19.443Z] 22:13:19     INFO - TinderboxPrint: CPU user<br/>1,703.6 (77.2%)
[task 2021-04-11T22:13:19.443Z] 22:13:19     INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2021-04-11T22:13:19.443Z] 22:13:19     INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2021-04-11T22:13:19.443Z] 22:13:19     INFO - install - Wall time: 50s; CPU: 50.0%; Read bytes: 86016; Write bytes: 2062843904; Read time: 0; Write time: 238860
[task 2021-04-11T22:13:19.444Z] 22:13:19     INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2021-04-11T22:13:19.449Z] 22:13:19     INFO - run-tests - Wall time: 1054s; CPU: 93.0%; Read bytes: 182939648; Write bytes: 9776783360; Read time: 2892; Write time: 644604
[task 2021-04-11T22:13:19.590Z] 22:13:19  WARNING - returning nonzero exit status 2```

Valentin, can you take a look?

Flags: needinfo?(valentin.gosu)

Racy test :(

Assignee: nobody → valentin.gosu
Blocks: doh
Flags: needinfo?(valentin.gosu)
Whiteboard: [necko-triaged]

I suspect this was caused by bug 1743122.
We can reopen if it happens again

Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → WORKSFORME
See Also: → 1743122
You need to log in before you can comment on or make changes to this bug.