Closed Bug 1806494 Opened 1 year ago Closed 1 year ago

Intermittent [tier 2] toolkit/components/extensions/test/xpcshell/test_ext_dnr_urlFilter.js | single tracking bug

Categories

(WebExtensions :: General, defect, P5)

defect

Tracking

(firefox115 fixed)

RESOLVED FIXED
115 Branch
Tracking Status
firefox115 --- fixed

People

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

References

Details

(Keywords: intermittent-failure, intermittent-testcase, Whiteboard: [retriggered][addons-jira])

Attachments

(1 file)

Filed by: sstanca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=400131466&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/eYxAVownRA-S2T-QKhZHhg/runs/1/artifacts/public/logs/live_backing.log


[task 2022-12-19T18:27:44.281Z] 18:27:44     INFO -  TEST-PASS | toolkit/components/reputationservice/test/unit/test_app_rep_maclinux.js | took 549ms
[task 2022-12-19T18:27:44.283Z] 18:27:44     INFO -  Retrying tests that failed when run in parallel.
[task 2022-12-19T18:27:44.286Z] 18:27:44     INFO -  TEST-START | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_dnr_urlFilter.js
[task 2022-12-19T18:32:44.286Z] 18:32:44  WARNING -  TEST-UNEXPECTED-TIMEOUT | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_dnr_urlFilter.js | Test timed out
[task 2022-12-19T18:32:44.291Z] 18:32:44     INFO -  TEST-INFO took 300001ms
[task 2022-12-19T18:32:44.291Z] 18:32:44     INFO -  >>>>>>>
[task 2022-12-19T18:32:44.291Z] 18:32:44     INFO -  PID 26955 | [CodeCoverage] Setting handlers for process 26955.
[task 2022-12-19T18:32:44.302Z] 18:32:44     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2022-12-19T18:32:44.302Z] 18:32:44     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2022-12-19T18:32:44.303Z] 18:32:44     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2022-12-19T18:32:44.303Z] 18:32:44     INFO -  running event loop
[task 2022-12-19T18:32:44.303Z] 18:32:44     INFO -  xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_dnr_urlFilter.js | Starting setup check_remote
[task 2022-12-19T18:32:44.304Z] 18:32:44     INFO -  (xpcshell/head.js) | test check_remote pending (2)
[task 2022-12-19T18:32:44.304Z] 18:32:44     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_dnr_urlFilter.js | check_remote - [check_remote : 1] useRemoteWebExtensions matches - true == true

Looking at the test it isn't really obvious to me that it would be affected by the CSP change. The test does seem to test quite a few permutations and linux1804-64-ccov-qr is usually slower. Maybe just a real timeout?

Flags: needinfo?(evilpies) → needinfo?(rob)

This is a ccov-only failure. It is not caused by bug 1804145. I don't see why it would trigger a ccov failure, but if it somehow persists, we could consider moving part of the test to a separate file and add skip-if = ccov (https://searchfox.org/mozilla-central/rev/abf6758ed833c203f84703aa2e3e3d317571b1e9/toolkit/components/extensions/test/xpcshell/test_ext_dnr_urlFilter.js#273-352).

The logs show that the test successfully completes the logic within specific test task (i.e. extreme_urlFilter_patterns), but that it is then stuck without finishing the specific task:

Both examples show successful completion of the tests. These tests themselves involve calling APIs with a few megabytes of (generated) string data as parameters that don't persist past the end of the test task. Example 2 also logs "CONSOLE_MESSAGE: (error) [JavaScript Error: "Timeout while waiting for extension child to shutdown: "Generated extension"". All reported lines are associated with almost the same timestamp, with a few ms difference. Either the test runs fast, or the reported timestamps are unreliable.

18:32:44 INFO - (xpcshell/head.js) | test urlFilter_domain_anchor finished (2)
18:32:44 INFO - xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_dnr_urlFilter.js | Starting extreme_urlFilter_patterns
18:32:44 INFO - (xpcshell/head.js) | test extreme_urlFilter_patterns pending (2)
18:32:44 INFO - "Extension attached"
18:32:44 INFO - (xpcshell/head.js) | test run_next_test 4 finished (2)
18:32:44 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_dnr_urlFilter.js | extreme_urlFilter_patterns - [extreme_urlFilter_patterns : 165] urlFilter with 1M wildcard chars matches any URL - Expected: [1], Actual: [1] - true == true
18:32:44 INFO - PID 26955 | [CodeCoverage] Setting handlers for process 27052.
18:32:44 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_dnr_urlFilter.js | extreme_urlFilter_patterns - [extreme_urlFilter_patterns : 165] urlFilter with 1M wildcards matches, other '^' do not match alpha - Expected: [1], Actual: [1] - true == true
18:32:44 INFO - PID 26955 | [CodeCoverage] Setting handlers for process 27065.
18:32:44 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_dnr_urlFilter.js | extreme_urlFilter_patterns - [extreme_urlFilter_patterns : 165] urlFilter with 1M wildcards, ^ and *^ all match URL with 1M '/' chars - Expected: [1,2,3], Actual: [1,2,3] - true == true
18:32:44 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_dnr_urlFilter.js | extreme_urlFilter_patterns - [extreme_urlFilter_patterns : 165] urlFilter with 1M wildcards and *^ match URL with 1M 'x/' chars - Expected: [1,3], Actual: [1,3] - true == true
18:32:44 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_dnr_urlFilter.js | extreme_urlFilter_patterns - [extreme_urlFilter_patterns : 165] urlFilter with 1M and 10M wildcards matches URL - Expected: [1,4], Actual: [1,4] - true == true
18:32:44 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_dnr_urlFilter.js | extreme_urlFilter_patterns - [extreme_urlFilter_patterns : 180] nulltrue == true
18:32:44 INFO - <<<<<<<
18:32:44 INFO - xpcshell return code: None
18:32:44 INFO - xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_dnr_urlFilter.js | Process still running after test!^M
18:49:24 INFO - Automation Error: mozprocess timed out after 1000 seconds running (...)

Assignee: nobody → rob
Status: NEW → ASSIGNED
Flags: needinfo?(rob)
Assignee: rob → nobody
Status: ASSIGNED → NEW

I don't see the failures improving on its own, so I'm going to skip the specific part in ccov.

Assignee: nobody → rob
Status: NEW → ASSIGNED
Whiteboard: [retriggered] → [retriggered][addons-jira]
Pushed by rob@robwu.nl:
https://hg.mozilla.org/integration/autoland/rev/4cfea48018be
Skip part of test_ext_dnr_urlFilter.js in ccov r=rpl
Status: ASSIGNED → RESOLVED
Closed: 1 year ago
Resolution: --- → FIXED
Target Milestone: --- → 115 Branch
See Also: → 1848663
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: