1756023 - Intermittent [tier 2] toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | received onErrorOccurred

Status: RESOLVED DUPLICATE of bug 1777023

(WebExtensions :: General, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2022-02-17T18:22:04.217Z] 18:22:04     INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html
<...>
[task 2022-02-17T18:22:10.065Z] 18:22:10     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | received onResponseStarted 
[task 2022-02-17T18:22:10.066Z] 18:22:10     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | resource type is correct - Expected: main_frame, Actual: main_frame 
[task 2022-02-17T18:22:10.067Z] 18:22:10     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | origin is correct - Expected: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html?currentTestURL=toolkit%2Fcomponents%2Fextensions%2Ftest%2Fmochitest%2Ftest_ext_webrequest_filter.html&closeWhenDone=1&showTestReport=false&expected=pass, Actual: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html?currentTestURL=toolkit%2Fcomponents%2Fextensions%2Ftest%2Fmochitest%2Ftest_ext_webrequest_filter.html&closeWhenDone=1&showTestReport=false&expected=pass 
[task 2022-02-17T18:22:10.068Z] 18:22:10     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | correct requestId - Expected: 1092, Actual: 1092 
[task 2022-02-17T18:22:10.068Z] 18:22:10     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | correct tabId - Expected: 165, Actual: 165 
[task 2022-02-17T18:22:10.069Z] 18:22:10     INFO - onErrorOccurred 1092 http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/file_image_good.png?r=0.16508565098215267
[task 2022-02-17T18:22:10.069Z] 18:22:10     INFO - Buffered messages finished
[task 2022-02-17T18:22:10.070Z] 18:22:10     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | received onErrorOccurred 
[task 2022-02-17T18:22:10.070Z] 18:22:10     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2022-02-17T18:22:10.071Z] 18:22:10     INFO -     testHandler@SimpleTest/ExtensionTestUtils.js:84:18
[task 2022-02-17T18:22:10.071Z] 18:22:10     INFO -     testResult@SimpleTest/ExtensionTestUtils.js:94:18
[task 2022-02-17T18:22:10.072Z] 18:22:10     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | resource type is correct - Expected: main_frame, Actual: main_frame 
[task 2022-02-17T18:22:10.072Z] 18:22:10     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | origin is correct - Expected: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html?currentTestURL=toolkit%2Fcomponents%2Fextensions%2Ftest%2Fmochitest%2Ftest_ext_webrequest_filter.html&closeWhenDone=1&showTestReport=false&expected=pass, Actual: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html?currentTestURL=toolkit%2Fcomponents%2Fextensions%2Ftest%2Fmochitest%2Ftest_ext_webrequest_filter.html&closeWhenDone=1&showTestReport=false&expected=pass 
[task 2022-02-17T18:22:10.073Z] 18:22:10     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | correct requestId - Expected: 1092, Actual: 1092 
[task 2022-02-17T18:22:10.073Z] 18:22:10     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | correct tabId - Expected: 165, Actual: 165 

Comment 1

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

All 3 observed test failures are on Linux with cross-origin and fission enabled.

Comment 2

[Sean Feng [:sefeng]]

Hi aryx,

I am trying to reproduce it locally..er, is cross-origin a new feature, I believe fission is enabled by default now, how do I enable cross-origin?

Comment 3

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

This needs --enable-xorigin-tests when launched from the command line.

Comment 4

[Timothy Nikkel (:tnikkel)]

FYI, xorigin mochitests mean the following: all mochitests are run in an iframe, xorigin mochitests mean that iframe is xorigin to its parent document, usually it is not.

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 7
• mozilla-central: 1

Platform and build breakdown:

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

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

Comment 6

[Intermittent Failures Robot]

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

This is the #34 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: 25
• mozilla-central: 4
• try: 1

Platform and build breakdown:

• linux1804-64-shippable-qr: 10
  • opt: 10
• linux1804-64-qr: 20
  • opt: 20

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

Comment 7

[Andreea Pavel [:apavel]]

There are 40 total failures in the last 7 days on

• linux1804-64-shippable-qr opt
• linux1804-64-qr opt
  Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=369857442&repo=autoland&lineNumber=6744

[task 2022-03-03T20:29:26.275Z] 20:29:26     INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html
[task 2022-03-03T20:29:27.137Z] 20:29:27     INFO - GECKO(16581) | Console message: Warning: attempting to write 20404 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 2022-03-03T20:29:27.190Z] 20:29:27     INFO - GECKO(16581) | Console message: [JavaScript Warning: "Storage access automatically granted for origin “http://mochi.test:8888” on “http://mochi.xorigin-test:8888”."]
[task 2022-03-03T20:29:28.661Z] 20:29:28     INFO - GECKO(16581) | Console message: Warning: attempting to write 20484 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 2022-03-03T20:29:30.290Z] 20:29:30     INFO - GECKO(16581) | Console message: [JavaScript Warning: "Storage access automatically granted for origin “http://mochi.test:8888” on “http://mochi.xorigin-test:8888”."]
[task 2022-03-03T20:29:30.360Z] 20:29:30     INFO - GECKO(16581) | JavaScript error: , line 0: TypeError: NetworkError when attempting to fetch resource.
[task 2022-03-03T20:29:30.388Z] 20:29:30     INFO - GECKO(16581) | Console message: [JavaScript Error: "Cross-Origin Request Blocked: The Same Origin Policy disallows reading the remote resource at https://example.com/example.txt. (Reason: CORS header ‘Access-Control-Allow-Origin’ missing). Status code: 404."]
[task 2022-03-03T20:29:30.390Z] 20:29:30     INFO - GECKO(16581) | Console message: [JavaScript Error: "TypeError: NetworkError when attempting to fetch resource."]
[task 2022-03-03T20:29:31.161Z] 20:29:31     INFO - GECKO(16581) | Console message: Warning: attempting to write 20564 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 2022-03-03T20:29:31.482Z] 20:29:31     INFO - GECKO(16581) | Console message: [JavaScript Warning: "Storage access automatically granted for origin “http://mochi.test:8888” on “http://mochi.xorigin-test:8888”."]
[task 2022-03-03T20:29:32.810Z] 20:29:32     INFO - GECKO(16581) | JavaScript error: , line 0: TypeError: NetworkError when attempting to fetch resource.
[task 2022-03-03T20:29:32.817Z] 20:29:32     INFO - GECKO(16581) | Console message: [JavaScript Error: "Cross-Origin Request Blocked: The Same Origin Policy disallows reading the remote resource at https://example.com/example.txt. (Reason: CORS header ‘Access-Control-Allow-Origin’ missing). Status code: 404."]
[task 2022-03-03T20:29:32.819Z] 20:29:32     INFO - GECKO(16581) | Console message: [JavaScript Error: "TypeError: NetworkError when attempting to fetch resource."]
[task 2022-03-03T20:29:32.921Z] 20:29:32     INFO - TEST-INFO | started process screentopng
[task 2022-03-03T20:29:33.185Z] 20:29:33     INFO - TEST-INFO | screentopng: exit 0
[task 2022-03-03T20:29:33.186Z] 20:29:33     INFO - <snipped 41 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
[task 2022-03-03T20:29:33.186Z] 20:29:33     INFO - Buffered messages logged at 20:29:30
[task 2022-03-03T20:29:33.187Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | expected HTTP status received for http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/file_image_bad.png HTTP/1.1 200 OK - Expected: 200, Actual: 200 
[task 2022-03-03T20:29:33.187Z] 20:29:33     INFO - onResponseStarted 1114 http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/file_image_bad.png
[task 2022-03-03T20:29:33.189Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | received onResponseStarted 
[task 2022-03-03T20:29:33.189Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | resource type is correct - Expected: main_frame, Actual: main_frame 
[task 2022-03-03T20:29:33.190Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | origin is correct - Expected: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html?currentTestURL=toolkit%2Fcomponents%2Fextensions%2Ftest%2Fmochitest%2Ftest_ext_webrequest_filter.html&closeWhenDone=1&showTestReport=false&expected=pass, Actual: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html?currentTestURL=toolkit%2Fcomponents%2Fextensions%2Ftest%2Fmochitest%2Ftest_ext_webrequest_filter.html&closeWhenDone=1&showTestReport=false&expected=pass 
[task 2022-03-03T20:29:33.191Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | correct requestId - Expected: 1114, Actual: 1114 
[task 2022-03-03T20:29:33.192Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | correct tabId - Expected: 170, Actual: 170 
[task 2022-03-03T20:29:33.193Z] 20:29:33     INFO - onCompleted 1114 http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/file_image_bad.png
[task 2022-03-03T20:29:33.194Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | received onCompleted 
[task 2022-03-03T20:29:33.196Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | resource type is correct - Expected: main_frame, Actual: main_frame 
[task 2022-03-03T20:29:33.197Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | origin is correct - Expected: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html?currentTestURL=toolkit%2Fcomponents%2Fextensions%2Ftest%2Fmochitest%2Ftest_ext_webrequest_filter.html&closeWhenDone=1&showTestReport=false&expected=pass, Actual: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html?currentTestURL=toolkit%2Fcomponents%2Fextensions%2Ftest%2Fmochitest%2Ftest_ext_webrequest_filter.html&closeWhenDone=1&showTestReport=false&expected=pass 
[task 2022-03-03T20:29:33.199Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | correct requestId - Expected: 1114, Actual: 1114 
[task 2022-03-03T20:29:33.200Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | correct tabId - Expected: 170, Actual: 170 
[task 2022-03-03T20:29:33.206Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | IP for http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/file_image_bad.png looks IP-ish: 127.0.0.1 
[task 2022-03-03T20:29:33.208Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | correct ip for http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/file_image_bad.png - Expected: 127.0.0.1, Actual: 127.0.0.1 
[task 2022-03-03T20:29:33.209Z] 20:29:33     INFO - Buffered messages logged at 20:29:31
[task 2022-03-03T20:29:33.211Z] 20:29:33     INFO - onBeforeRequest 1115 http://mochi.test:8888/favicon.ico

task 2022-03-03T20:29:33.317Z] 20:29:33     INFO - onHeadersReceived 1121 http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/file_image_good.png?r=0.4784250250397759
[task 2022-03-03T20:29:33.318Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | received onHeadersReceived 
[task 2022-03-03T20:29:33.319Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | resource type is correct - Expected: main_frame, Actual: main_frame 
[task 2022-03-03T20:29:33.321Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | origin is correct - Expected: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html?currentTestURL=toolkit%2Fcomponents%2Fextensions%2Ftest%2Fmochitest%2Ftest_ext_webrequest_filter.html&closeWhenDone=1&showTestReport=false&expected=pass, Actual: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html?currentTestURL=toolkit%2Fcomponents%2Fextensions%2Ftest%2Fmochitest%2Ftest_ext_webrequest_filter.html&closeWhenDone=1&showTestReport=false&expected=pass 
[task 2022-03-03T20:29:33.323Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | correct requestId - Expected: 1121, Actual: 1121 
[task 2022-03-03T20:29:33.324Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | correct tabId - Expected: 170, Actual: 170 
[task 2022-03-03T20:29:33.326Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | expected HTTP status received for http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/file_image_good.png?r=0.4784250250397759 HTTP/1.1 200 OK - Expected: 200, Actual: 200 
[task 2022-03-03T20:29:33.327Z] 20:29:33     INFO - onResponseStarted 1121 http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/file_image_good.png?r=0.4784250250397759
[task 2022-03-03T20:29:33.329Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | received onResponseStarted 
[task 2022-03-03T20:29:33.330Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | resource type is correct - Expected: main_frame, Actual: main_frame 
[task 2022-03-03T20:29:33.332Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | origin is correct - Expected: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html?currentTestURL=toolkit%2Fcomponents%2Fextensions%2Ftest%2Fmochitest%2Ftest_ext_webrequest_filter.html&closeWhenDone=1&showTestReport=false&expected=pass, Actual: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html?currentTestURL=toolkit%2Fcomponents%2Fextensions%2Ftest%2Fmochitest%2Ftest_ext_webrequest_filter.html&closeWhenDone=1&showTestReport=false&expected=pass 
[task 2022-03-03T20:29:33.334Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | correct requestId - Expected: 1121, Actual: 1121 
[task 2022-03-03T20:29:33.335Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | correct tabId - Expected: 170, Actual: 170 
[task 2022-03-03T20:29:33.342Z] 20:29:33     INFO - onErrorOccurred 1121 http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/file_image_good.png?r=0.4784250250397759
[task 2022-03-03T20:29:33.344Z] 20:29:33     INFO - Buffered messages finished
[task 2022-03-03T20:29:33.345Z] 20:29:33     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | received onErrorOccurred 
[task 2022-03-03T20:29:33.346Z] 20:29:33     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2022-03-03T20:29:33.346Z] 20:29:33     INFO -     testHandler@SimpleTest/ExtensionTestUtils.js:84:18
[task 2022-03-03T20:29:33.347Z] 20:29:33     INFO -     testResult@SimpleTest/ExtensionTestUtils.js:94:18
[task 2022-03-03T20:29:33.347Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | resource type is correct - Expected: main_frame, Actual: main_frame 
[task 2022-03-03T20:29:33.348Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | origin is correct - Expected: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html?currentTestURL=toolkit%2Fcomponents%2Fextensions%2Ftest%2Fmochitest%2Ftest_ext_webrequest_filter.html&closeWhenDone=1&showTestReport=false&expected=pass, Actual: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html?currentTestURL=toolkit%2Fcomponents%2Fextensions%2Ftest%2Fmochitest%2Ftest_ext_webrequest_filter.html&closeWhenDone=1&showTestReport=false&expected=pass 
[task 2022-03-03T20:29:33.349Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | correct requestId - Expected: 1121, Actual: 1121 
[task 2022-03-03T20:29:33.349Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | correct tabId - Expected: 170, Actual: 170 
[task 2022-03-03T20:29:33.491Z] 20:29:33     INFO - onBeforeRequest 1122 http://mochi.test:8888/favicon.ico
[task 2022-03-03T20:29:33.493Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | received onBeforeRequest 
[task 2022-03-03T20:29:33.549Z] 20:29:33     INFO - onCompleted 1122 http://mochi.test:8888/favicon.ico
[task 2022-03-03T20:29:33.551Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | received onCompleted 
[task 2022-03-03T20:29:33.552Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | resource type is correct - Expected: image, Actual: image 
[task 2022-03-03T20:29:33.554Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | origin is correct - Expected: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/file_image_good.png?r=0.4784250250397759, Actual: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/file_image_good.png?r=0.4784250250397759 
[task 2022-03-03T20:29:33.555Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | correct requestId - Expected: 1122, Actual: 1122 
[task 2022-03-03T20:29:33.556Z] 20:29:33     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | correct tabId - Expected: 170, Actual: 170 
[task 2022-03-03T20:34:32.877Z] 20:34:32     INFO - GECKO(16581) | 1646339672874	addons.xpi	ERROR	System addon update list error Error: got node name: html, expected: updates
[task 2022-03-03T20:34:32.878Z] 20:34:32     INFO - GECKO(16581) | Console message: [JavaScript Error: "1646339672874	addons.xpi	ERROR	System addon update list error Error: got node name: html, expected: updates" {file: "resource://gre/modules/Log.jsm" line: 723}]
[task 2022-03-03T20:34:32.879Z] 20:34:32     INFO - GECKO(16581) | append@resource://gre/modules/Log.jsm:723:12
[task 2022-03-03T20:34:32.880Z] 20:34:32     INFO - GECKO(16581) | log@resource://gre/modules/Log.jsm:379:16
[task 2022-03-03T20:34:32.880Z] 20:34:32     INFO - GECKO(16581) | error@resource://gre/modules/Log.jsm:387:10
[task 2022-03-03T20:34:32.880Z] 20:34:32     INFO - GECKO(16581) | updateSystemAddons/res<@resource://gre/modules/addons/XPIInstall.jsm:4073:25
[task 2022-03-03T20:34:38.775Z] 20:34:38     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-03-03T20:34:38.776Z] 20:34:38     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | Test timed out. - 
[task 2022-03-03T20:34:39.778Z] 20:34:39     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-03-03T20:34:39.790Z] 20:34:39     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | Extension left running at test shutdown 
[task 2022-03-03T20:34:39.790Z] 20:34:39     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2022-03-03T20:34:39.790Z] 20:34:39     INFO -     ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:129:18
[task 2022-03-03T20:34:39.790Z] 20:34:39     INFO -     executeCleanupFunction@SimpleTest/SimpleTest.js:1487:13
[task 2022-03-03T20:34:39.790Z] 20:34:39     INFO -     SimpleTest.finish@SimpleTest/SimpleTest.js:1501:3
[task 2022-03-03T20:34:39.790Z] 20:34:39     INFO -     @SimpleTest/SimpleTest.js:2141:16
[task 2022-03-03T20:34:39.805Z] 20:34:39     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-03-03T20:34:39.805Z] 20:34:39     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | no tasks awaiting on messages - got "[\"done\"]", expected "[]"
[task 2022-03-03T20:34:39.805Z] 20:34:39     INFO -     SimpleTest.is@SimpleTest/SimpleTest.js:500:14
[task 2022-03-03T20:34:39.806Z] 20:34:39     INFO -     ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:48:18
[task 2022-03-03T20:34:39.807Z] 20:34:39     INFO -     executeCleanupFunction@SimpleTest/SimpleTest.js:1487:13
[task 2022-03-03T20:34:39.809Z] 20:34:39     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-03-03T20:34:39.810Z] 20:34:39     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | Test left extra windows or tabs: {"extraWindows":{"0":{"0":"http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/file_image_good.png?r=0.4784250250397759"}},"extraTabs":{}}
[task 2022-03-03T20:34:39.810Z] 20:34:39     INFO -  
[task 2022-03-03T20:34:39.810Z] 20:34:39     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2022-03-03T20:34:39.810Z] 20:34:39     INFO -     @toolkit/components/extensions/test/mochitest/head.js:33:9
[task 2022-03-03T20:34:39.813Z] 20:34:39     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-03-03T20:34:39.813Z] 20:34:39     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | This test left a service worker registered without cleaning it up 
[task 2022-03-03T20:34:39.813Z] 20:34:39     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2022-03-03T20:34:39.813Z] 20:34:39     INFO -     afterCleanup@SimpleTest/SimpleTest.js:1436:18
[task 2022-03-03T20:34:39.816Z] 20:34:39     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-03-03T20:34:39.816Z] 20:34:39     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | Left over worker: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/webrequest_worker.js?test1 (scope: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/) 
[task 2022-03-03T20:34:39.816Z] 20:34:39     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2022-03-03T20:34:39.816Z] 20:34:39     INFO -     afterCleanup@SimpleTest/SimpleTest.js:1441:20
[task 2022-03-03T20:34:39.824Z] 20:34:39     INFO - GECKO(16581) | MEMORY STAT | vsize 2489MB | residentFast 120MB | heapAllocated 8MB
[task 2022-03-03T20:34:39.831Z] 20:34:39     INFO - TEST-OK | toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html | took 313556ms
[task 2022-03-03T20:34:42.834Z] 20:34:42     INFO - Error: Unable to restore focus, expect failures and timeouts.

Sean, are you still working on this? Are there any updates?

Comment 8

[Intermittent Failures Robot]

43 failures in 3063 pushes (0.014 failures/push) were associated with this bug in the last 7 days.

This is the #17 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: 32
• mozilla-central: 10
• try: 1

Platform and build breakdown:

• linux1804-64-shippable-qr: 25
  • opt: 25
• linux1804-64-qr: 18
  • opt: 18

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1756023&startday=2022-02-28&endday=2022-03-06&tree=all

Comment 9

[Marian-Vasile Laza]

There have been 33 total failures in the last 7 days, recent failure log.
Affected platforms are:

• linux1804-64-qr
• linux1804-64-shippable-qr

Comment 10

[Intermittent Failures Robot]

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

This is the #42 most frequent failure this week.

Repository breakdown:

• autoland: 23
• mozilla-central: 5
• try: 1

Platform and build breakdown:

• linux1804-64-qr: 11
  • opt: 11
• linux1804-64-shippable-qr: 18
  • opt: 18

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

Comment 11

[Cristian Tuns]

Attachment: Bug 1756023 - disable test_ext_webrequest_filter.html on linux x64 r=#intermittent-reviewers

Comment 12

[Intermittent Failures Robot]

40 failures in 3304 pushes (0.012 failures/push) were associated with this bug in the last 7 days.

This is the #22 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: 32
• mozilla-central: 7
• try: 1

Platform and build breakdown:

• linux1804-64-qr: 19
  • opt: 19
• linux1804-64-shippable-qr: 21
  • opt: 21

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

Comment 13

[Sean Feng [:sefeng]]

So I took Timothy's suggestion to make a backout patch and see if the failure still appears.

Try push: https://treeherder.mozilla.org/jobs?repo=try&revision=167199976ae2d90e2912f642b039df10d4629997&selectedTaskRun=M6Bub3L6QVqrWfAvitr1IQ.0

The failure still persists with the backout patch, so it's caused by another patch I think?

Sorry about the late updates.

Comment 14

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/ffa34c347fa0
disable test_ext_webrequest_filter.html on linux x64 r=intermittent-reviewers,jmaher

Comment 15

[Cosmin Sabou [:CosminS]]

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

Comment 16

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

Yes, some Try pushes with backouts are further evidence it's intermittent and don't point at a specific commit.

Comment 17

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 9
• mozilla-central: 3
• try: 1

Platform and build breakdown:

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

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