Closed Bug 1756023 Opened 10 months ago Closed 4 months ago

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

Categories

(WebExtensions :: General, defect, P5)

defect

Tracking

(firefox-esr91 unaffected, firefox97 unaffected, firefox98 unaffected, firefox99 wontfix, firefox100 disabled, firefox101 disabled)

RESOLVED DUPLICATE of bug 1777023
Tracking Status
firefox-esr91 --- unaffected
firefox97 --- unaffected
firefox98 --- unaffected
firefox99 --- wontfix
firefox100 --- disabled
firefox101 --- disabled

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Blocks 1 open bug, Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [stockwell disabled])

Attachments

(1 file)

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 

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

Has Regression Range: --- → yes
Flags: needinfo?(sefeng)
Keywords: regression
Regressed by: 1755226

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?

Flags: needinfo?(sefeng) → needinfo?(aryx.bugmail)

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

Flags: needinfo?(aryx.bugmail)

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.

There are 40 total failures in the last 7 days on

[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?

Flags: needinfo?(sefeng)
Whiteboard: [stockwell needswork:owner]

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

  • linux1804-64-qr
  • linux1804-64-shippable-qr
Assignee: nobody → ctuns
Status: NEW → ASSIGNED
Assignee: ctuns → nobody
Status: ASSIGNED → NEW
Keywords: leave-open

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.

Flags: needinfo?(sefeng) → needinfo?(aryx.bugmail)
Pushed by jmaher@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/ffa34c347fa0
disable test_ext_webrequest_filter.html on linux x64 r=intermittent-reviewers,jmaher

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

Flags: needinfo?(aryx.bugmail)
Whiteboard: [stockwell needswork:owner] → [stockwell disabled]
Status: NEW → RESOLVED
Closed: 4 months ago
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.