1633189 - Intermittent toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | Test timed out.

Status: ASSIGNED

(WebExtensions :: General, defect, P3)

Description

[Treeherder Bug Filer]

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

---

[task 2020-04-25T11:29:59.123Z] 11:29:59 INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html
[task 2020-04-25T11:30:00.525Z] 11:30:00 INFO - GECKO(29867) | Console message: [JavaScript Warning: "Loading failed for the <script> with source “http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/file_script_bad.js”." {file: "http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html" line: 1}]
[task 2020-04-25T11:30:00.785Z] 11:30:00 INFO - GECKO(29867) | Console message: [JavaScript Warning: "Synchronous XMLHttpRequest on the main thread is deprecated because of its detrimental effects to the end user’s experience. For more help http://xhr.spec.whatwg.org/" {file: "http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/file_script_xhr.js" line: 4}]
[task 2020-04-25T11:30:00.983Z] 11:30:00 INFO - GECKO(29867) | Console message: [JavaScript Warning: "Loading failed for the <script> with source “http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/nonexistent_script_url.js”." {file: "http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html" line: 1}]
[task 2020-04-25T11:30:01.322Z] 11:30:01 INFO - GECKO(29867) | Console message: [JavaScript Warning: "Loading failed for the <script> with source “http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/nonexistent_script_url.js”." {file: "http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html" line: 1}]
[task 2020-04-25T11:30:01.479Z] 11:30:01 INFO - GECKO(29867) | Console message: [JavaScript Warning: "Loading failed for the <script> with source “http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/file_script_nonexistent.js”." {file: "http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html" line: 1}]
[task 2020-04-25T11:30:03.833Z] 11:30:03 INFO - GECKO(29867) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-04-25T11:30:04.354Z] 11:30:04 INFO - GECKO(29867) | [CodeCoverage] Setting handlers for process 30207.
[task 2020-04-25T11:30:05.244Z] 11:30:05 INFO - GECKO(29867) | [CodeCoverage] Requested flush for 29867.
[task 2020-04-25T11:30:05.522Z] 11:30:05 INFO - GECKO(29867) | [CodeCoverage] flush completed.
[task 2020-04-25T11:30:06.196Z] 11:30:06 INFO - GECKO(29867) | [CodeCoverage] JS flush completed.
[task 2020-04-25T11:30:06.196Z] 11:30:06 INFO - GECKO(29867) | [CodeCoverage] Requested flush for 30047.
[task 2020-04-25T11:30:06.196Z] 11:30:06 INFO - GECKO(29867) | [CodeCoverage] Requested flush for 30207.
[task 2020-04-25T11:30:06.198Z] 11:30:06 INFO - GECKO(29867) | [CodeCoverage] Requested flush for 30031.
[task 2020-04-25T11:30:06.461Z] 11:30:06 INFO - GECKO(29867) | [CodeCoverage] flush completed.
[task 2020-04-25T11:30:06.485Z] 11:30:06 INFO - GECKO(29867) | [CodeCoverage] JS flush completed.
[task 2020-04-25T11:30:06.768Z] 11:30:06 INFO - GECKO(29867) | [CodeCoverage] flush completed.
[task 2020-04-25T11:30:06.836Z] 11:30:06 INFO - GECKO(29867) | [CodeCoverage] JS flush completed.
[task 2020-04-25T11:30:07.127Z] 11:30:07 INFO - GECKO(29867) | [CodeCoverage] flush completed.
[task 2020-04-25T11:30:07.288Z] 11:30:07 INFO - GECKO(29867) | [CodeCoverage] JS flush completed.
[task 2020-04-25T11:30:07.292Z] 11:30:07 INFO - GECKO(29867) | JavaScript error: resource://testing-common/PerTestCoverageUtils.jsm, line 76: NS_ERROR_FILE_DIR_NOT_EMPTY: Component returned failure code: 0x80520014 (NS_ERROR_FILE_DIR_NOT_EMPTY) [nsIFile.moveTo]
[task 2020-04-25T11:35:18.745Z] 11:35:18 INFO - TEST-INFO | started process screentopng
[task 2020-04-25T11:35:18.923Z] 11:35:18 INFO - TEST-INFO | screentopng: exit 0
[task 2020-04-25T11:35:18.925Z] 11:35:18 INFO - <snipped 786 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
[task 2020-04-25T11:35:18.927Z] 11:35:18 INFO - Buffered messages logged at 11:30:04
[task 2020-04-25T11:35:18.928Z] 11:35:18 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | valid resource type sub_frame

[task 2020-04-25T11:35:18.992Z] 11:35:18 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | User-Agent header matched and changed.
[task 2020-04-25T11:35:18.992Z] 11:35:18 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | User-Agent header blindly added.
[task 2020-04-25T11:35:18.994Z] 11:35:18 INFO - add_task | Leaving test test_case_preserving
[task 2020-04-25T11:35:18.994Z] 11:35:18 INFO - Buffered messages finished
[task 2020-04-25T11:35:18.996Z] 11:35:18 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | Test timed out.
[task 2020-04-25T11:35:18.996Z] 11:35:18 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-04-25T11:35:18.996Z] 11:35:18 INFO - reportError@SimpleTest/TestRunner.js:128:22
[task 2020-04-25T11:35:18.997Z] 11:35:18 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:150:18
[task 2020-04-25T11:35:18.997Z] 11:35:18 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-25T11:35:18.997Z] 11:35:18 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-25T11:35:18.997Z] 11:35:18 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-25T11:35:18.997Z] 11:35:18 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-25T11:35:18.997Z] 11:35:18 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-25T11:35:18.997Z] 11:35:18 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-25T11:35:18.997Z] 11:35:18 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-25T11:35:18.997Z] 11:35:18 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-25T11:35:18.998Z] 11:35:18 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-25T11:35:18.998Z] 11:35:18 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-25T11:35:18.998Z] 11:35:18 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-25T11:35:18.998Z] 11:35:18 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:420:16
[task 2020-04-25T11:35:18.998Z] 11:35:18 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:407:48
[task 2020-04-25T11:35:18.998Z] 11:35:18 INFO - RunSet.runtests@SimpleTest/setup.js:218:14
[task 2020-04-25T11:35:18.998Z] 11:35:18 INFO - RunSet.runall@SimpleTest/setup.js:197:12
[task 2020-04-25T11:35:18.998Z] 11:35:18 INFO - hookupTests@SimpleTest/setup.js:294:12
[task 2020-04-25T11:35:18.998Z] 11:35:18 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:46:13
[task 2020-04-25T11:35:18.999Z] 11:35:18 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:59:28
[task 2020-04-25T11:35:18.999Z] 11:35:18 INFO - EventHandlerNonNullgetTestManifest@http://mochi.test:8888/manifestLibrary.js:55:3
[task 2020-04-25T11:35:18.999Z] 11:35:18 INFO - hookup@SimpleTest/setup.js:270:20
[task 2020-04-25T11:35:18.999Z] 11:35:18 INFO - EventHandlerNonNull@toolkit/components/extensions/test/mochitest?autorun=1&closeWhenDone=1&consoleLevel=INFO&testname=tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true:11:1
[task 2020-04-25T11:35:19.745Z] 11:35:19 ERROR - [SimpleTest.finish()] this test already called finish!
[task 2020-04-25T11:35:19.753Z] 11:35:19 INFO - GECKO(29867) | [CodeCoverage] Requested flush for 29867.
[task 2020-04-25T11:35:20.035Z] 11:35:20 INFO - GECKO(29867) | [CodeCoverage] flush completed.
[task 2020-04-25T11:35:20.689Z] 11:35:20 INFO - GECKO(29867) | [CodeCoverage] JS flush completed.
[task 2020-04-25T11:35:20.689Z] 11:35:20 INFO - GECKO(29867) | [CodeCoverage] Requested flush for 30031.
[task 2020-04-25T11:35:20.690Z] 11:35:20 INFO - GECKO(29867) | [CodeCoverage] Requested flush for 30047.
[task 2020-04-25T11:35:20.690Z] 11:35:20 INFO - GECKO(29867) | [CodeCoverage] Requested flush for 30207.
[task 2020-04-25T11:35:20.968Z] 11:35:20 INFO - GECKO(29867) | [CodeCoverage] flush completed.
[task 2020-04-25T11:35:21.036Z] 11:35:21 INFO - GECKO(29867) | [CodeCoverage] JS flush completed.
[task 2020-04-25T11:35:21.323Z] 11:35:21 INFO - GECKO(29867) | [CodeCoverage] flush completed.
[task 2020-04-25T11:35:21.488Z] 11:35:21 INFO - GECKO(29867) | [CodeCoverage] JS flush completed.
[task 2020-04-25T11:35:21.766Z] 11:35:21 INFO - GECKO(29867) | [CodeCoverage] flush completed.
[task 2020-04-25T11:35:21.786Z] 11:35:21 INFO - GECKO(29867) | [CodeCoverage] JS flush completed.
[task 2020-04-25T11:35:21.794Z] 11:35:21 INFO - GECKO(29867) | JavaScript error: resource://testing-common/PerTestCoverageUtils.jsm, line 76: NS_ERROR_FILE_DIR_NOT_EMPTY: Component returned failure code: 0x80520014 (NS_ERROR_FILE_DIR_NOT_EMPTY) [nsIFile.moveTo]
[task 2020-04-25T11:35:48.745Z] 11:35:48 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-04-25T11:35:48.746Z] 11:35:48 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | Test timed out.

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux1804-64-ccov: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1633189&startday=2020-04-20&endday=2020-04-26&tree=all

Comment 2

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• linux1804-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1633189&startday=2020-06-22&endday=2020-06-28&tree=all

Comment 4

[Cristian Brindusan [:cbrindusan]]

New occurrence:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=311485708&repo=autoland&lineNumber=15367

Comment 5

[Andreea Pavel [:apavel]]

Retriggers: https://treeherder.mozilla.org/#/jobs?repo=autoland&group_state=expanded&searchStr=linux%2C18.04%2Cx64%2Cwebrender%2Cdebug%2Cmochitests%2Cwith%2Cfission%2Cenabled%2Ctest-linux1804-64-qr%2Fdebug-mochitest-plain-fis-e10s%2C2&tochange=d4e18da4508ec84aa05daaa90b2340a275ece6e4&fromchange=e29d13db9888cec75e8e368a76f159e46d9a1c68&selectedTaskRun=HhEnHMexRG-N0ZlPyXGtrg.0

Masayuki, please take a look.

Comment 6

[Masayuki Nakano [:masayuki] (he/him)(JST, +0900)]

The patch just removed unused listening API:

• https://searchfox.org/mozilla-central/search?q=DidDeleteSelection&path=&case=false&regexp=false
• https://searchfox.org/mozilla-central/search?q=DidSplitNode&path=&case=false&regexp=false
• https://searchfox.org/mozilla-central/search?q=DidInsertNode&path=&case=false&regexp=false
• https://searchfox.org/mozilla-central/search?q=DidCreateNode&path=&case=false&regexp=false

Actually, there are no methods named as them except mozInlineSpecllChecker, but it's just a same name method, not a part of nsIEditActionListener. So, the patch might change some race condition due to dropping the loops notifying listeners.

And looks like the test is a suite of various testing. I'm not sure whether some of them touch editor or not yet. I'll keep checking, but according to the log, the test is really unstable and changed a lot of time. So, I'd like to ask the maintainer, mixedpuppy, what's going on here.

Comment 7

[Masayuki Nakano [:masayuki] (he/him)(JST, +0900)]

[task 2020-07-30T09:35:27.734Z] 09:35:27     INFO - onErrorOccurred 1176 http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/file_style_bad.css
[task 2020-07-30T09:35:27.734Z] 09:35:27     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | received onErrorOccurred 
[task 2020-07-30T09:35:27.735Z] 09:35:27     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | resource type is correct - Expected: stylesheet, Actual: stylesheet 
[task 2020-07-30T09:35:27.736Z] 09:35:27     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | origin is correct - Expected: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html, Actual: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html 
[task 2020-07-30T09:35:27.736Z] 09:35:27     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | correct requestId - Expected: 1176, Actual: 1176 
[task 2020-07-30T09:35:27.736Z] 09:35:27     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | correct tabId - Expected: 1, Actual: 1 
[task 2020-07-30T09:35:27.737Z] 09:35:27     INFO - Buffered messages finished
[task 2020-07-30T09:35:27.737Z] 09:35:27     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | Test timed out. 
[task 2020-07-30T09:35:27.737Z] 09:35:27     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:412:16
[task 2020-07-30T09:35:27.737Z] 09:35:27     INFO -     reportError@SimpleTest/TestRunner.js:138:22
[task 2020-07-30T09:35:27.737Z] 09:35:27     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:160:18
[task 2020-07-30T09:35:28.560Z] 09:35:28     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-07-30T09:35:28.572Z] 09:35:28     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | Extension left running at test shutdown 
[task 2020-07-30T09:35:28.573Z] 09:35:28     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:412:16
[task 2020-07-30T09:35:28.573Z] 09:35:28     INFO -     ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:117:18
[task 2020-07-30T09:35:28.574Z] 09:35:28     INFO -     executeCleanupFunction@SimpleTest/SimpleTest.js:1587:13
[task 2020-07-30T09:35:28.574Z] 09:35:28     INFO -     SimpleTest.finish@SimpleTest/SimpleTest.js:1601:3
[task 2020-07-30T09:35:28.574Z] 09:35:28     INFO -     killTest@SimpleTest/TestRunner.js:147:22
[task 2020-07-30T09:35:28.583Z] 09:35:28     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-07-30T09:35:28.583Z] 09:35:28     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | no tasks awaiting on messages - got "[\"done\"]", expected "[]"
[task 2020-07-30T09:35:28.583Z] 09:35:28     INFO -     SimpleTest.is@SimpleTest/SimpleTest.js:495:14
[task 2020-07-30T09:35:28.584Z] 09:35:28     INFO -     ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:36:18
[task 2020-07-30T09:35:28.584Z] 09:35:28     INFO -     executeCleanupFunction@SimpleTest/SimpleTest.js:1587:13
[task 2020-07-30T09:35:29.603Z] 09:35:29     INFO - GECKO(8448) | MEMORY STAT | vsize 2588MB | residentFast 174MB | heapAllocated 17MB
[task 2020-07-30T09:35:31.626Z] 09:35:31     INFO - TEST-OK | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | took 331021ms
[task 2020-07-30T09:35:34.632Z] 09:35:34     INFO - Error: Unable to restore focus, expect failures and timeouts.
[task 2020-07-30T09:35:34.660Z] 09:35:34    ERROR - /tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html logged result after SimpleTest.finish(): Extension left running at test shutdown
[task 2020-07-30T09:35:34.660Z] 09:35:34    ERROR - /tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html logged result after SimpleTest.finish(): no tasks awaiting on messages

Oh, there is an error message. It's here:
https://searchfox.org/mozilla-central/rev/03794edd6edcc3fc1e222de966cb27256ce08998/testing/mochitest/tests/SimpleTest/TestRunner.js#365

According to the message, Fission's focus handling is something changed before my landing or my patch just change the race condition in focused process.

Comment 8

[Masayuki Nakano [:masayuki] (he/him)(JST, +0900)]

Looks like the hang occurs here:
https://searchfox.org/mozilla-central/rev/03794edd6edcc3fc1e222de966cb27256ce08998/toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html#81-84

Both stylesheets change only foreground color of #text.
https://searchfox.org/mozilla-central/source/toolkit/components/extensions/test/mochitest/file_style_bad.css
https://searchfox.org/mozilla-central/source/toolkit/components/extensions/test/mochitest/file_style_redirect.css

"cancelled" message is sent from here:
https://searchfox.org/mozilla-central/rev/03794edd6edcc3fc1e222de966cb27256ce08998/toolkit/components/extensions/test/mochitest/head_webrequest.js#389

addStyleSheet() modifies the DOM synchronously to add <link> element to load the stylesheet.
https://searchfox.org/mozilla-central/rev/03794edd6edcc3fc1e222de966cb27256ce08998/toolkit/components/extensions/test/mochitest/head_webrequest.js#436-441

So, I guess that at this line: await extension.awaitMessage("cancelled");, the load has already done in Fission world.

Comment 9

[Intermittent Failures Robot]

21 failures in 4289 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 21

Platform breakdown:

• linux1804-64-qr: 19
• linux1804-64-asan: 2

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

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• linux1804-64-qr: 1

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

Comment 11

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 2

Platform breakdown:

• linux1804-64-qr: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1633189&startday=2020-09-07&endday=2020-09-13&tree=all

Comment 12

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4
• try: 1

Platform breakdown:

• linux1804-64-qr: 4
• linux1804-64-asan: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1633189&startday=2020-09-14&endday=2020-09-20&tree=all

Comment 13

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• macosx1014-64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1633189&startday=2020-09-28&endday=2020-10-04&tree=all

Comment 14

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 6

Platform breakdown:

• linux1804-64-qr: 4
• macosx1014-64: 2

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

Comment 15

[Intermittent Failures Robot]

14 failures in 3694 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 14

Platform breakdown:

• linux1804-64-qr: 12
• linux1804-64: 1
• macosx1014-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1633189&startday=2020-10-12&endday=2020-10-18&tree=all

Comment 16

[Shane Caraveo (:mixedpuppy)]

Attachment: Bug 1633189 address intermittent cache request failures with style sheets in webrequest test

Comment 17

[Shane Caraveo (:mixedpuppy)]

I just added a query param to avoid cache with a couple of the stylesheet tests. The rest of the patch change is the code formatter. Let me know if you're fine with this approach to avoid style sheet issues here.

Comment 18

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 5

Platform breakdown:

• linux1804-64-qr: 4
• macosx1014-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1633189&startday=2020-10-19&endday=2020-10-25&tree=all

Comment 19

[Intermittent Failures Robot]

10 failures in 4275 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 10

Platform breakdown:

• linux1804-64-qr: 10

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

Comment 20

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 6

Platform breakdown:

• linux1804-64-qr: 6

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

Comment 21

[Intermittent Failures Robot]

34 failures in 747 pushes (0.046 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 34

Platform breakdown:

• linux1804-64-qr: 32
• linux1804-64: 2

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

Comment 22

[Andreea Pavel [:apavel]]

There are 39 total failures in the last 7 days on linux1804-64-qr opt

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=321669860&repo=autoland&lineNumber=6729

task 2020-11-13T06:37:39.373Z] 06:37:39 INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html
[task 2020-11-13T06:37:44.474Z] 06:37:44 INFO - GECKO(2890) | Console message: Warning: attempting to write 22227 bytes to preference extensions.webextensions.uuids. This is bad for general performance and memory usage. Such an amount of data should rather be written to an external file. This preference will not be sent to any content processes.
[task 2020-11-13T06:40:39.712Z] 06:40:39 INFO - GECKO(2890) | 1605249639701 addons.xpi ERROR System addon update list error Error: got node name: html, expected: updates
[task 2020-11-13T06:40:39.713Z] 06:40:39 INFO - GECKO(2890) | Console message: [JavaScript Error: "1605249639701 addons.xpi ERROR System addon update list error Error: got node name: html, expected: updates" {file: "resource://gre/modules/Log.jsm" line: 723}]
[task 2020-11-13T06:40:39.714Z] 06:40:39 INFO - GECKO(2890) | append@resource://gre/modules/Log.jsm:723:12
[task 2020-11-13T06:40:39.714Z] 06:40:39 INFO - GECKO(2890) | log@resource://gre/modules/Log.jsm:379:16
[task 2020-11-13T06:40:39.714Z] 06:40:39 INFO - GECKO(2890) | error@resource://gre/modules/Log.jsm:387:10
[task 2020-11-13T06:40:39.715Z] 06:40:39 INFO - GECKO(2890) | updateSystemAddons/res<@resource://gre/modules/addons/XPIInstall.jsm:4015:25
[task 2020-11-13T06:42:48.816Z] 06:42:48 INFO - TEST-INFO | started process screentopng
[task 2020-11-13T06:42:48.957Z] 06:42:48 INFO - TEST-INFO | screentopng: exit 0
[task 2020-11-13T06:42:48.959Z] 06:42:48 INFO - Buffered messages logged at 06:37:42
[task 2020-11-13T06:42:48.960Z] 06:42:48 INFO - Error: Unable to restore focus, expect failures and timeouts.
[task 2020-11-13T06:42:48.961Z] 06:42:48 INFO - Buffered messages logged at 06:37:43
[task 2020-11-13T06:42:48.961Z] 06:42:48 INFO - add_task | Entering test setup
[task 2020-11-13T06:42:48.961Z] 06:42:48 INFO - Buffered messages logged at 06:37:44
[task 2020-11-13T06:42:48.962Z] 06:42:48 INFO - Extension loaded
[task 2020-11-13T06:42:48.963Z] 06:42:48 INFO - adding listener for onBeforeRequest
[task 2020-11-13T06:42:48.963Z] 06:42:48 INFO - adding listener for onBeforeSendHeaders
[task 2020-11-13T06:42:48.964Z] 06:42:48 INFO - adding listener for onSendHeaders
[task 2020-11-13T06:42:48.964Z] 06:42:48 INFO - adding listener for onBeforeRedirect
[task 2020-11-13T06:42:48.964Z] 06:42:48 INFO - adding listener for onHeadersReceived
[task 2020-11-13T06:42:48.965Z] 06:42:48 INFO - adding listener for onResponseStarted
[task 2020-11-13T06:42:48.965Z] 06:42:48 INFO - adding listener for onCompleted
[task 2020-11-13T06:42:48.965Z] 06:42:48 INFO - adding listener for onErrorOccurred
[task 2020-11-13T06:42:48.966Z] 06:42:48 INFO - add_task | Leaving test setup
[task 2020-11-13T06:42:48.966Z] 06:42:48 INFO - add_task | Entering test test_webRequest_links
[task 2020-11-13T06:42:48.966Z] 06:42:48 INFO - onBeforeRequest 1321 http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/file_style_bad.css
[task 2020-11-13T06:42:48.966Z] 06:42:48 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | received onBeforeRequest
[task 2020-11-13T06:42:48.967Z] 06:42:48 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | resource type is correct - Expected: stylesheet, Actual: stylesheet
[task 2020-11-13T06:42:48.967Z] 06:42:48 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | origin is correct - Expected: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html, Actual: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html
[task 2020-11-13T06:42:48.969Z] 06:42:48 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | tabId 1
[task 2020-11-13T06:42:48.969Z] 06:42:48 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | requestId 1321
[task 2020-11-13T06:42:48.970Z] 06:42:48 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | valid resource type stylesheet
[task 2020-11-13T06:42:48.970Z] 06:42:48 INFO - onBeforeRequest cancel request
[task 2020-11-13T06:42:48.971Z] 06:42:48 INFO - onErrorOccurred 1321 http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/file_style_bad.css
[task 2020-11-13T06:42:48.971Z] 06:42:48 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | received onErrorOccurred
[task 2020-11-13T06:42:48.971Z] 06:42:48 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | resource type is correct - Expected: stylesheet, Actual: stylesheet
[task 2020-11-13T06:42:48.973Z] 06:42:48 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | origin is correct - Expected: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html, Actual: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html
[task 2020-11-13T06:42:48.973Z] 06:42:48 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | correct requestId - Expected: 1321, Actual: 1321
[task 2020-11-13T06:42:48.973Z] 06:42:48 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | correct tabId - Expected: 1, Actual: 1
[task 2020-11-13T06:42:48.974Z] 06:42:48 INFO - Buffered messages finished
[task 2020-11-13T06:42:48.974Z] 06:42:48 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | Test timed out.
[task 2020-11-13T06:42:48.974Z] 06:42:48 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2020-11-13T06:42:48.974Z] 06:42:48 INFO - reportError@SimpleTest/TestRunner.js:143:22
[task 2020-11-13T06:42:48.975Z] 06:42:48 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:165:18
[task 2020-11-13T06:42:49.823Z] 06:42:49 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-11-13T06:42:49.824Z] 06:42:49 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | Extension left running at test shutdown
[task 2020-11-13T06:42:49.824Z] 06:42:49 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2020-11-13T06:42:49.824Z] 06:42:49 INFO - ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:117:18
[task 2020-11-13T06:42:49.824Z] 06:42:49 INFO - executeCleanupFunction@SimpleTest/SimpleTest.js:1619:13
[task 2020-11-13T06:42:49.824Z] 06:42:49 INFO - SimpleTest.finish@SimpleTest/SimpleTest.js:1633:3
[task 2020-11-13T06:42:49.825Z] 06:42:49 INFO - killTest@SimpleTest/TestRunner.js:152:22
[task 2020-11-13T06:42:49.831Z] 06:42:49 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-11-13T06:42:49.832Z] 06:42:49 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | no tasks awaiting on messages - got "["done"]", expected "[]"
[task 2020-11-13T06:42:49.832Z] 06:42:49 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:500:14
[task 2020-11-13T06:42:49.832Z] 06:42:49 INFO - ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:36:18
[task 2020-11-13T06:42:49.833Z] 06:42:49 INFO - executeCleanupFunction@SimpleTest/SimpleTest.js:1619:13
[task 2020-11-13T06:42:50.832Z] 06:42:50 INFO - GECKO(2890) | MEMORY STAT | vsize 2595MB | residentFast 155MB | heapAllocated 16MB
[task 2020-11-13T06:42:52.841Z] 06:42:52 INFO - TEST-OK | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | took 313468ms
[task 2020-11-13T06:42:55.848Z] 06:42:55 INFO - Error: Unable to restore focus, expect failures and timeouts.
[task 2020-11-13T06:42:55.851Z] 06:42:55 ERROR - TEST-UNEXPECTED-FAIL | /tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html logged result after SimpleTest.finish(): Extension left running at test shutdown
[task 2020-11-13T06:42:55.853Z] 06:42:55 ERROR - TEST-UNEXPECTED-FAIL | /tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html logged result after SimpleTest.finish(): no tasks awaiting on messages

Shane are there any updates here?

Comment 23

[Intermittent Failures Robot]

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

This is the #31 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 39

Platform breakdown:

• linux1804-64-qr: 37
• linux1804-64: 2

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

Comment 24

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• linux1804-64-qr: 2

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

Comment 25

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• linux1804-64: 2

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

Comment 26

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• linux1804-64-qr: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2020-11-30&endday=2020-12-06&tree=all

Comment 27

[BugBot [:suhaib / :marco/ :calixte]]

There's a r+ patch which didn't land and no activity in this bug for 2 weeks.
:mixedpuppy, could you have a look please?
For more information, please visit auto_nag documentation.

Comment 28

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-01-18&endday=2021-01-24&tree=all

Comment 29

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4

Platform and build breakdown:

• linux1804-64-qr: 2
  • opt: 2
• linux1804-64: 1
  • opt: 1
• macosx1014-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-01-25&endday=2021-01-31&tree=all

Comment 30

[Alexandru Michis [:malexandru]]

Shane, could you please take a look at these instances of Bug 1633189?
https://treeherder.mozilla.org/jobs?repo=autoland&group_state=expanded&resultStatus=testfailed%2Cbusted%2Cexception%2Csuccess%2Crunning%2Cpending%2Crunnable&fromchange=1e0238293dea9dd83644ff46dca31c9046339dcb&searchStr=linux%2C18.04%2Cx64%2Cwebrender%2Copt%2Cmochitests%2Ctest-linux1804-64-qr%2Fopt-mochitest-plain-e10s%2C1&tochange=285f6a34e600b48b8fff0188f931dedf6729307f&selectedTaskRun=M0jdEaa1RmGN-i00pfGTbg.0

They seem to be permafailing on those two specific pushes and not on those who run all manifests.

Comment 31

[Intermittent Failures Robot]

11 failures in 3966 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 11

Platform and build breakdown:

• linux1804-64: 1
  • opt: 1
• linux1804-64-qr: 8
  • opt: 8
• macosx1014-64-qr: 2
  • debug: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-02-01&endday=2021-02-07&tree=all

Comment 32

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-02-08&endday=2021-02-14&tree=all

Comment 33

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4

Platform and build breakdown:

• linux1804-64-qr: 4
  • opt: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-02-15&endday=2021-02-21&tree=all

Comment 34

[Shane Caraveo (:mixedpuppy)]

masayuki made a comment in review that caused me to pause on this, I haven't got around to looking back for a different way to handle this. If it gets worse I'll revisit.

Comment 35

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4

Platform and build breakdown:

• linux1804-64-qr: 4
  • opt: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-02-22&endday=2021-02-28&tree=all

Comment 36

[Intermittent Failures Robot]

9 failures in 3683 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 9

Platform and build breakdown:

• linux1804-64-qr: 9
  • opt: 9

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-03-01&endday=2021-03-07&tree=all

Comment 37

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• linux1804-64-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-03-08&endday=2021-03-14&tree=all

Comment 38

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4

Platform and build breakdown:

• linux1804-64-qr: 2
  • opt: 2
• macosx1014-64-qr: 2
  • opt: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-03-15&endday=2021-03-21&tree=all

Comment 39

[Intermittent Failures Robot]

9 failures in 3967 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 9

Platform and build breakdown:

• linux1804-64-qr: 3
  • opt: 3
• macosx1014-64-qr: 5
  • opt: 1
  • debug: 4
• linux1804-64: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-03-22&endday=2021-03-28&tree=all

Comment 40

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• linux1804-64-qr: 1
  • opt: 1
• macosx1014-64-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-03-29&endday=2021-04-04&tree=all

Comment 41

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 6

Platform and build breakdown:

• macosx1014-64-qr: 3
  • debug: 3
• linux1804-64-qr: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-04-05&endday=2021-04-11&tree=all

Comment 42

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• linux1804-64-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-04-12&endday=2021-04-18&tree=all

Comment 43

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• macosx1015-64-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-04-19&endday=2021-04-25&tree=all

Comment 44

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 6

Platform and build breakdown:

• linux1804-64-qr: 6
  • opt: 2
  • debug: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-05-03&endday=2021-05-09&tree=all

Comment 45

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-05-17&endday=2021-05-23&tree=all

Comment 46

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/f7f5e7f11445
address intermittent cache request failures with style sheets in webrequest test r=masayuki

Comment 47

[Atila Butkovits]

Backed out for causing failures at test_ext_webrequest_basic.html.

Backout link: https://hg.mozilla.org/integration/autoland/rev/5d03a9d6cb8aeba1bb488b989712ad9cfa0546d2

Push with failures: https://treeherder.mozilla.org/jobs?repo=autoland&selectedTaskRun=aNSzfxiZTmaUqrde55r3uw.0&resultStatus=testfailed%2Cbusted%2Cexception%2Cretry%2Cusercancel&revision=f7f5e7f114456f56849bc90bef0a875f226afdc6&searchStr=linux%2C18.04%2Cx64%2Casan%2Copt%2Cmochitests%2Ctest-linux1804-64-asan%2Fopt-mochitest-plain-e10s%2C9

Failure log: https://treeherder.mozilla.org/logviewer?job_id=340998028&repo=autoland&lineNumber=3605

Comment 48

[Intermittent Failures Robot]

12 failures in 2908 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 11
• mozilla-central: 1

Platform and build breakdown:

• macosx1015-64-qr: 2
  • debug: 2
• linux1804-64-qr: 3
  • opt: 2
  • debug: 1
• macosx1014-64-qr: 4
  • debug: 2
  • opt: 2
• linux1804-64: 2
  • debug: 1
  • opt: 1
• linux1804-64-ccov: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-05-24&endday=2021-05-30&tree=all

Comment 49

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 6

Platform and build breakdown:

• linux1804-64-qr: 6
  • opt: 6

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-05-31&endday=2021-06-06&tree=all

Comment 50

[Intermittent Failures Robot]

7 failures in 3115 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 2
• autoland: 5

Platform and build breakdown:

• linux1804-64: 3
  • opt: 3
• macosx1015-64-qr: 3
  • debug: 2
  • opt: 1
• linux1804-64-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-06-21&endday=2021-06-27&tree=all

Comment 51

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• linux1804-64-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-07-12&endday=2021-07-18&tree=all

Comment 52

[Cosmin Sabou [:CosminS]]

https://hg.mozilla.org/integration/autoland/rev/16b06a2c00b0 turned this into a tier 2 perma-fail, treeherder link.
Jeff, could you have a look over this? Thank you.

Comment 53

[Jeff Muizelaar [:jrmuizel]]

https://hg.mozilla.org/integration/autoland/rev/16b06a2c00b0 is pretty unrelated to these tests and it's hard to imagine how it could've caused this to perma-fail other than by changing the timing.

Shane, based on the history in this bug, it seems like this test has issues. Should it just be disabled?

Comment 54

[Intermittent Failures Robot]

25 failures in 622 pushes (0.04 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 25

Platform and build breakdown:

• linux1804-64-asan-qr: 23
  • opt: 23
• macosx1015-64-qr: 2
  • debug: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-07-30&endday=2021-07-30&tree=all

Comment 55

[Intermittent Failures Robot]

26 failures in 3131 pushes (0.008 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 26

Platform and build breakdown:

• linux1804-64-qr: 1
  • opt: 1
• linux1804-64-asan-qr: 23
  • opt: 23
• macosx1015-64-qr: 2
  • debug: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-07-26&endday=2021-08-01&tree=all

Comment 56

[Intermittent Failures Robot]

29 failures in 2829 pushes (0.01 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 29

Platform and build breakdown:

• linux1804-64-asan-qr: 29
  • opt: 29

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-08-02&endday=2021-08-08&tree=all

Comment 57

[Intermittent Failures Robot]

18 failures in 3025 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 16
• try: 2

Platform and build breakdown:

• linux1804-64-asan-qr: 16
  • opt: 16
• linux1804-64-qr: 2
  • opt: 2

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

Comment 58

[Iulian Moraru]

Update:
There have been 30 failures within the last 7 days:
• 26 failures on Linux 18.04 x64 WebRender asan opt
• 1 failures on Linux 18.04 x64 WebRender opt
• 1 failures on OS X 10.15 WebRender opt
• 2 failures on OS X 10.15 WebRender debug
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=349142097&repo=autoland&lineNumber=5801

[task 2021-08-21T18:03:10.091Z] 18:03:10     INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html
[task 2021-08-21T18:03:10.120Z] 18:03:10     INFO - GECKO(1877) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-08-21T18:03:10.121Z] 18:03:10     INFO - GECKO(1877) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-08-21T18:03:10.923Z] 18:03:10     INFO - GECKO(1877) | -----------------------------------------------------
[task 2021-08-21T18:03:10.924Z] 18:03:10     INFO - GECKO(1877) | Suppressions used:
[task 2021-08-21T18:03:10.925Z] 18:03:10     INFO - GECKO(1877) |   count      bytes template
[task 2021-08-21T18:03:10.926Z] 18:03:10     INFO - GECKO(1877) |      13        416 nsComponentManagerImpl
[task 2021-08-21T18:03:10.926Z] 18:03:10     INFO - GECKO(1877) |       2        288 libfontconfig.so
[task 2021-08-21T18:03:10.927Z] 18:03:10     INFO - GECKO(1877) | -----------------------------------------------------
[task 2021-08-21T18:03:14.325Z] 18:03:14     INFO - GECKO(1877) | Console message: Warning: attempting to write 22777 bytes to preference extensions.webextensions.uuids. This is bad for general performance and memory usage. Such an amount of data should rather be written to an external file. This preference will not be sent to any content processes.
[task 2021-08-21T18:08:20.615Z] 18:08:20     INFO - TEST-INFO | started process screentopng
[task 2021-08-21T18:08:20.790Z] 18:08:20     INFO - TEST-INFO | screentopng: exit 0
[task 2021-08-21T18:08:20.791Z] 18:08:20     INFO - Buffered messages logged at 18:03:13
[task 2021-08-21T18:08:20.793Z] 18:08:20     INFO - Error: Unable to restore focus, expect failures and timeouts.
[task 2021-08-21T18:08:20.794Z] 18:08:20     INFO - Buffered messages logged at 18:03:14
[task 2021-08-21T18:08:20.796Z] 18:08:20     INFO - add_task | Entering test setup
[task 2021-08-21T18:08:20.796Z] 18:08:20     INFO - Extension loaded
[task 2021-08-21T18:08:20.797Z] 18:08:20     INFO - adding listener for onBeforeRequest
[task 2021-08-21T18:08:20.797Z] 18:08:20     INFO - adding listener for onBeforeSendHeaders
[task 2021-08-21T18:08:20.797Z] 18:08:20     INFO - adding listener for onSendHeaders
[task 2021-08-21T18:08:20.798Z] 18:08:20     INFO - adding listener for onBeforeRedirect
[task 2021-08-21T18:08:20.798Z] 18:08:20     INFO - adding listener for onHeadersReceived
[task 2021-08-21T18:08:20.798Z] 18:08:20     INFO - adding listener for onResponseStarted
[task 2021-08-21T18:08:20.798Z] 18:08:20     INFO - adding listener for onCompleted
[task 2021-08-21T18:08:20.799Z] 18:08:20     INFO - adding listener for onErrorOccurred
[task 2021-08-21T18:08:20.799Z] 18:08:20     INFO - add_task | Leaving test setup
[task 2021-08-21T18:08:20.800Z] 18:08:20     INFO - add_task | Entering test test_webRequest_links
[task 2021-08-21T18:08:20.800Z] 18:08:20     INFO - onBeforeRequest 1740 http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/file_style_bad.css
[task 2021-08-21T18:08:20.802Z] 18:08:20     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | received onBeforeRequest 
[task 2021-08-21T18:08:20.802Z] 18:08:20     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | resource type is correct - Expected: stylesheet, Actual: stylesheet 
[task 2021-08-21T18:08:20.803Z] 18:08:20     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | origin is correct - Expected: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html, Actual: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html 
[task 2021-08-21T18:08:20.803Z] 18:08:20     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | tabId 1 
[task 2021-08-21T18:08:20.804Z] 18:08:20     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | requestId 1740 
[task 2021-08-21T18:08:20.805Z] 18:08:20     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | valid resource type stylesheet 
[task 2021-08-21T18:08:20.805Z] 18:08:20     INFO - onBeforeRequest cancel request
[task 2021-08-21T18:08:20.806Z] 18:08:20     INFO - onErrorOccurred 1740 http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/file_style_bad.css
[task 2021-08-21T18:08:20.807Z] 18:08:20     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | received onErrorOccurred 
[task 2021-08-21T18:08:20.807Z] 18:08:20     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | resource type is correct - Expected: stylesheet, Actual: stylesheet 
[task 2021-08-21T18:08:20.807Z] 18:08:20     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | origin is correct - Expected: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html, Actual: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html 
[task 2021-08-21T18:08:20.808Z] 18:08:20     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | correct requestId - Expected: 1740, Actual: 1740 
[task 2021-08-21T18:08:20.808Z] 18:08:20     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | correct tabId - Expected: 1, Actual: 1 
[task 2021-08-21T18:08:20.808Z] 18:08:20     INFO - Buffered messages finished
[task 2021-08-21T18:08:20.808Z] 18:08:20     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | Test timed out. - 
[task 2021-08-21T18:08:21.634Z] 18:08:21     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-08-21T18:08:21.636Z] 18:08:21     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | Extension left running at test shutdown 
[task 2021-08-21T18:08:21.637Z] 18:08:21     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2021-08-21T18:08:21.637Z] 18:08:21     INFO -     ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:117:18
[task 2021-08-21T18:08:21.637Z] 18:08:21     INFO -     executeCleanupFunction@SimpleTest/SimpleTest.js:1487:13
[task 2021-08-21T18:08:21.637Z] 18:08:21     INFO -     SimpleTest.finish@SimpleTest/SimpleTest.js:1501:3
[task 2021-08-21T18:08:21.637Z] 18:08:21     INFO -     killTest@SimpleTest/TestRunner.js:194:22
[task 2021-08-21T18:08:21.647Z] 18:08:21     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-08-21T18:08:21.647Z] 18:08:21     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | no tasks awaiting on messages - got "[\"done\"]", expected "[]"
[task 2021-08-21T18:08:21.648Z] 18:08:21     INFO -     SimpleTest.is@SimpleTest/SimpleTest.js:500:14
[task 2021-08-21T18:08:21.648Z] 18:08:21     INFO -     ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:36:18
[task 2021-08-21T18:08:21.648Z] 18:08:21     INFO -     executeCleanupFunction@SimpleTest/SimpleTest.js:1487:13
[task 2021-08-21T18:08:22.659Z] 18:08:22     INFO - GECKO(1877) | MEMORY STAT | vsize 20974639MB | residentFast 927MB
[task 2021-08-21T18:08:23.673Z] 18:08:23     INFO - TEST-OK | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | took 313581ms
[task 2021-08-21T18:08:26.679Z] 18:08:26     INFO - Error: Unable to restore focus, expect failures and timeouts.
[task 2021-08-21T18:08:26.701Z] 18:08:26    ERROR - TEST-UNEXPECTED-FAIL | /tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html logged result after SimpleTest.finish(): Extension left running at test shutdown
[task 2021-08-21T18:08:26.702Z] 18:08:26    ERROR - TEST-UNEXPECTED-FAIL | /tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html logged result after SimpleTest.finish(): no tasks awaiting on messages
[task 2021-08-21T18:08:26.734Z] 18:08:26     INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_webrequest_errors.html

Comment 59

[Intermittent Failures Robot]

30 failures in 2899 pushes (0.01 failures/push) were associated with this bug in the last 7 days.

This is the #41 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 30

Platform and build breakdown:

• linux1804-64-asan-qr: 26
  • opt: 26
• linux1804-64-qr: 1
  • opt: 1
• macosx1015-64-qr: 3
  • debug: 2
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-08-16&endday=2021-08-22&tree=all

Comment 60

[Sandor Molnar[:smolnar]]

Attachment: Bug 1633189 - disable toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html for frequent linux-asan-opt failures. r=#intermittent-reviewers

Comment 61

[Rob Wu [:robwu]]

The specific failure of comment 58 is caused by the inability to detect a request for a CSS file, most likely because the file was already cached. The test could be fixed by wiping the stylesheet cache. I'm not sure why the ASAN build is more prone to this intermittent failure...

Possibly related: bug 1694939, bug 1657575 (maybe bug 1599160).

Comment 62

[Cristina Cozmuta (:CrissCozmuta)]

https://hg.mozilla.org/mozilla-central/rev/c665c7f04cf6

Comment 63

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 6

Platform and build breakdown:

• linux1804-64-asan-qr: 2
  • opt: 2
• macosx1015-64-qr: 3
  • debug: 3
• linux1804-64-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-08-23&endday=2021-08-29&tree=all

Comment 64

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3

Platform and build breakdown:

• linux1804-64-qr: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-09-13&endday=2021-09-19&tree=all

Comment 65

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• macosx1015-64-qr: 1
  • debug: 1
• linux1804-64-ccov-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-09-20&endday=2021-09-26&tree=all

Comment 66

[Natalia Csoregi [:nataliaCs]]

This is permafailing when a specific combination of tests run: https://treeherder.mozilla.org/jobs?repo=autoland&group_state=expanded&searchStr=mochitest-plain&revision=acab07e8dc6a0d57a544cb677a0740f3375bc250

• Manifests run:
  • docshell/test/mochitest/mochitest.ini
  • dom/security/test/csp/mochitest.ini
  • toolkit/components/extensions/test/mochitest/mochitest-remote.ini
  • toolkit/components/extensions/test/mochitest/mochitest.ini

Example of job with all tests scheduled: https://treeherder.mozilla.org/jobs?repo=autoland&group_state=expanded&selectedTaskRun=UuD8acRrTJWfvK8UN9n81Q.0&searchStr=mochitest-plain&test_paths=toolkit%2Fcomponents%2Fextensions%2Ftest%2Fmochitest&revision=9f585ad859364068120a85f87cc93b936dde5d11 . Noticed that the failure is not present.

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=353486397&repo=autoland&lineNumber=11831

[task 2021-10-03T08:47:37.847Z] 08:47:37     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | correct tabId - Expected: 1, Actual: 1 
[task 2021-10-03T08:47:37.848Z] 08:47:37     INFO - Buffered messages finished
[task 2021-10-03T08:47:37.848Z] 08:47:37     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | Test timed out. - 
[task 2021-10-03T08:47:38.805Z] 08:47:38     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-10-03T08:47:38.805Z] 08:47:38     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_webrequest_basic.html | Extension left running at test shutdown 
[task 2021-10-03T08:47:38.805Z] 08:47:38     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2021-10-03T08:47:38.805Z] 08:47:38     INFO -     ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:117:18
[task 2021-10-03T08:47:38.805Z] 08:47:38     INFO -     executeCleanupFunction@SimpleTest/SimpleTest.js:1487:13
[task 2021-10-03T08:47:38.805Z] 08:47:38     INFO -     SimpleTest.finish@SimpleTest/SimpleTest.js:1501:3
[task 2021-10-03T08:47:38.805Z] 08:47:38     INFO -     killTest@SimpleTest/TestRunner.js:194:22
[task 2021-10-03T08:47:38.816Z] 08:47:38     INFO - GECKO(3376) | [Child 3377, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2021-10-03T08:47:38.821Z] 08:47:38     INFO - Not taking screenshot here: see the one that was previously logged

Luca, is there any chance you could take a look at this?
Thank you!

Comment 67

[Natalia Csoregi [:nataliaCs]]

Marking comment above as obsolete. Moving ni request to Bug 1733781

Comment 68

[Intermittent Failures Robot]

21 failures in 3376 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 1
• autoland: 19
• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-qr: 3
  • opt: 2
  • debug: 1
• macosx1015-64-qr: 16
  • opt: 2
  • debug: 14
• linux1804-64-ccov-qr: 1
  • opt: 1
• android-hw-p2-8-0-android-aarch64-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-09-27&endday=2021-10-03&tree=all

Comment 69

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1633189&startday=2021-10-04&endday=2021-10-10&tree=all