1535451 - Perma Tier 2 devtools/client/webconsole/test/browser/browser_webconsole_trackingprotection_errors.js | Uncaught exception - waitFor - timed out after 500 tries.

Status: RESOLVED FIXED

(DevTools :: Console, defect, P5)

Description

[Treeherder Bug Filer]

#[markdown(off)]
Filed by: apavel [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=233959582&repo=autoland

https://queue.taskcluster.net/v1/task/NRyXMpjJTSaSWPEk39rmow/runs/0/artifacts/public/logs/live_backing.log

0:17:18 INFO - TEST-START | devtools/client/webconsole/test/mochitest/browser_webconsole_trackingprotection_errors.js
20:17:19 INFO - GECKO(4056) | _create_dc_and_bitmap: Not enough storage is available to process this command.
20:17:28 INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_trackingprotection_errors.js | Clicking the provided link opens in expected tab -
20:17:28 INFO - Leaving test bound testForeignCookieBlockedMessage
20:17:28 INFO - Entering test bound testLimitForeignCookieBlockedMessage
20:17:28 INFO - Test unvisited eTLD foreign cookies blocked message
20:17:28 INFO - Buffered messages logged at 20:17:23
20:17:28 INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/webconsole/test/mochitest/test-trackingprotection-securityerrors.html
20:17:28 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “http://tracking.example.org/” was blocked because we are blocking all third-party storage access requests and content blocking is enabled." {file: "http://example.com/browser/devtools/client/webconsole/test/mochitest/test-trackingprotection-securityerrors.html" line: 0}]
20:17:28 INFO - Tab added and finished loading
20:17:28 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “http://tracking.example.org/” was blocked because we are blocking all third-party storage access requests and content blocking is enabled." {file: "http://example.com/browser/devtools/client/webconsole/test/mochitest/test-trackingprotection-securityerrors.html" line: 0}]
20:17:28 INFO - Console message: [JavaScript Error: "uncaught exception: out of memory"]
20:17:28 INFO - Console message: [JavaScript Error: "uncaught exception: out of memory"]
20:17:28 INFO - Console message: [JavaScript Error: "uncaught exception: out of memory"]
20:17:28 INFO - Console message: [JavaScript Error: "uncaught exception: out of memory"]
20:17:28 INFO - Console message: [JavaScript Error: "uncaught exception: out of memory"]
20:17:28 INFO - Console message: [JavaScript Error: "uncaught exception: out of memory"]
20:17:28 INFO - Console message: [JavaScript Error: "uncaught exception: out of memory"]
20:17:28 INFO - Console message: [JavaScript Error: "uncaught exception: out of memory"]
20:17:28 INFO - Buffered messages finished
20:17:28 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/mochitest/browser_webconsole_trackingprotection_errors.js | Uncaught exception - waitFor - timed out after 500 tries.
20:17:28 INFO - Not taking screenshot here: see the one that was previously logged
20:17:28 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/mochitest/browser_webconsole_trackingprotection_errors.js | A promise chain failed to handle a rejection: out of memory - stack: (No stack available.)
20:17:28 INFO - Rejection date: Thu Mar 14 2019 20:17:23 GMT+0000 (Greenwich Mean Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 257
20:17:28 INFO - Stack trace:
20:17:28 INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:257
20:17:28 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1130
20:17:28 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1134
20:17:28 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:995
20:17:28 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:803
20:17:28 INFO - Leaving test bound testLimitForeignCookieBlockedMessage
20:17:28 INFO - Entering test bound testAllCookieBlockedMessage
20:17:28 INFO - Test all cookies blocked message
20:17:28 INFO - GECKO(4056) | [GFX1-]: Failed buffer for 0, 0, 1264, 1016
20:17:28 INFO - GECKO(4056) | _create_dc_and_bitmap: Not enough storage is available to process this command.

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1
• mozilla-central: 1

Platform breakdown:

• windows7-32: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1535451&startday=2019-03-11&endday=2019-03-17&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-inbound: 1

Platform breakdown:

• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1535451&startday=2019-03-18&endday=2019-03-24&tree=all

Comment 3

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Comment 4

[Raul Gurzau (:RaulG)]

New perma failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=263841004&repo=mozilla-central&lineNumber=5574

[task 2019-08-28T11:40:51.258Z] 11:40:51 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “http://tracking.example.org/browser/devtools/client/webconsole/test/browser/cookieSetter.html” was blocked because we are blocking all third-party storage access requests and content blocking is enabled." {file: "http://tracking.example.org/browser/devtools/client/webconsole/test/browser/cookieSetter.html" line: 0}]
[task 2019-08-28T11:40:51.258Z] 11:40:51 INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://tracking.example.org/browser/devtools/client/webconsole/test/browser/cookieSetter.html" line: 0}]
[task 2019-08-28T11:40:51.258Z] 11:40:51 INFO - Console message: [JavaScript Error: "SecurityError: Permission denied to access property "document" on cross-origin object" {file: "resource:///actors/PageStyleChild.jsm" line: 35}]
[task 2019-08-28T11:40:51.258Z] 11:40:51 INFO - Buffered messages logged at 11:40:40
[task 2019-08-28T11:40:51.259Z] 11:40:51 INFO - Clicking on the Learn More link
[task 2019-08-28T11:40:51.259Z] 11:40:51 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_trackingprotection_errors.js | Clicking the provided link opens https://developer.mozilla.org/docs/Mozilla/Firefox/Privacy/Storage_access_policy/Errors/CookieBlockedForeign?utm_source=devtools&utm_medium=firefox-cookie-errors&utm_campaign=default -
[task 2019-08-28T11:40:51.259Z] 11:40:51 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_trackingprotection_errors.js | Clicking the provided link opens in expected tab -
[task 2019-08-28T11:40:51.259Z] 11:40:51 INFO - Leaving test bound testForeignCookieBlockedMessage
[task 2019-08-28T11:40:51.259Z] 11:40:51 INFO - Entering test bound testLimitForeignCookieBlockedMessage
[task 2019-08-28T11:40:51.259Z] 11:40:51 INFO - Test unvisited eTLD foreign cookies blocked message
[task 2019-08-28T11:40:51.259Z] 11:40:51 INFO - Buffered messages logged at 11:40:44
[task 2019-08-28T11:40:51.259Z] 11:40:51 INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/webconsole/test/browser/test-trackingprotection-securityerrors.html
[task 2019-08-28T11:40:51.259Z] 11:40:51 INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://tracking.example.org/browser/devtools/client/webconsole/test/browser/cookieSetter.html" line: 0}]
[task 2019-08-28T11:40:51.259Z] 11:40:51 INFO - Tab added and finished loading
[task 2019-08-28T11:40:51.260Z] 11:40:51 INFO - Console message: [JavaScript Error: "SecurityError: Permission denied to access property "document" on cross-origin object" {file: "resource:///actors/PageStyleChild.jsm" line: 35}]
[task 2019-08-28T11:40:51.260Z] 11:40:51 INFO - Buffered messages finished
[task 2019-08-28T11:40:51.260Z] 11:40:51 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_webconsole_trackingprotection_errors.js | Uncaught exception - waitFor - timed out after 500 tries.
[task 2019-08-28T11:40:51.260Z] 11:40:51 INFO - Leaving test bound testLimitForeignCookieBlockedMessage
[task 2019-08-28T11:40:51.260Z] 11:40:51 INFO - Entering test bound testAllCookieBlockedMessage
[task 2019-08-28T11:40:51.260Z] 11:40:51 INFO - Test all cookies blocked message
[task 2019-08-28T11:40:51.525Z] 11:40:51 INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/webconsole/test/browser/test-trackingprotection-securityerrors.html
[task 2019-08-28T11:40:51.714Z] 11:40:51 INFO - GECKO(2376) | Assertion failure
[task 2019-08-28T11:40:51.715Z] 11:40:51 INFO - GECKO(2376) | assert@resource:///modules/AsyncTabSwitcher.jsm:490:36
[task 2019-08-28T11:40:51.715Z] 11:40:51 INFO - GECKO(2376) | onLayersCleared@resource:///modules/AsyncTabSwitcher.jsm:827:12
[task 2019-08-28T11:40:51.715Z] 11:40:51 INFO - GECKO(2376) | handleEvent@resource:///modules/AsyncTabSwitcher.jsm:1131:16
[task 2019-08-28T11:40:51.716Z] 11:40:51 INFO - GECKO(2376) | EventListener.handleEvent*AsyncTabSwitcher@resource:///modules/AsyncTabSwitcher.jsm:155:17
[task 2019-08-28T11:40:51.716Z] 11:40:51 INFO - GECKO(2376) | _getSwitcher@chrome://browser/content/tabbrowser.js:4705:26
[task 2019-08-28T11:40:51.719Z] 11:40:51 INFO - GECKO(2376) | updateCurrentBrowser@chrome://browser/content/tabbrowser.js:1019:16
[task 2019-08-28T11:40:51.720Z] 11:40:51 INFO - GECKO(2376) | _setupEventListeners/<@chrome://browser/content/tabbrowser.js:5107:16
[task 2019-08-28T11:40:51.720Z] 11:40:51 INFO - GECKO(2376) | set selectedIndex@chrome://global/content/elements/tabbox.js:208:14
[task 2019-08-28T11:40:51.720Z] 11:40:51 INFO - GECKO(2376) | set selectedPanel@chrome://global/content/elements/tabbox.js:227:7
[task 2019-08-28T11:40:51.720Z] 11:40:51 INFO - GECKO(2376) | set selectedIndex@chrome://global/content/elements/tabbox.js:571:11
[task 2019-08-28T11:40:51.720Z] 11:40:51 INFO - GECKO(2376) | set selectedItem@chrome://global/content/elements/tabbox.js:591:35
[task 2019-08-28T11:40:51.720Z] 11:40:51 INFO - GECKO(2376) | set selectedTab@chrome://global/content/elements/tabbox.js:98:11
[task 2019-08-28T11:40:51.720Z] 11:40:51 INFO - GECKO(2376) | set selectedTab@chrome://browser/content/tabbrowser.js:312:7

Comment 5

[Intermittent Failures Robot]

27 failures in 1055 pushes (0.026 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 27

Platform breakdown:

• linux64: 17
• windows10-64: 10

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1535451&startday=2019-08-28&endday=2019-08-28&tree=all

Comment 6

[Cosmin Sabou [:CosminS]]

Attachment: Bug 1535451 - Skip browser_webconsole_trackingprotection_errors.js on fission. r=nchevobbe

Comment 7

[Cosmin Sabou [:CosminS]]

This looks to be a fallout from Bug 1576841 https://hg.mozilla.org/mozilla-central/rev/da73ca6c2e2fc76729e589e1afe988754aab287d
Nicholas, can you please take a look?

Comment 8

[Intermittent Failures Robot]

20 failures in 1013 pushes (0.02 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 20

Platform breakdown:

• linux64: 15
• windows10-64: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1535451&startday=2019-08-29&endday=2019-08-29&tree=all

Comment 9

[Nicolas Chevobbe [:nchevobbe]]

Fix attempt: https://treeherder.mozilla.org/#/jobs?repo=try&revision=2b974c44af22b8587dd6f71c3f9ce48643d28bda

Comment 10

[Nicolas Chevobbe [:nchevobbe]]

Attachment: Bug 1535451 - Fix browser_webconsole_trackingprotection_errors.js intermittent. r=Honza.

Comment 11

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/59e90b5a43fa
Fix browser_webconsole_trackingprotection_errors.js intermittent. r=Honza.

Comment 12

[Stefan Hindli [:stefan_hindli]]

https://hg.mozilla.org/mozilla-central/rev/59e90b5a43fa

Comment 13

[Alexandru Michis [:malexandru]]

Nicolas, after the fix this is now perma failing in a similar manner:
https://treeherder.mozilla.org/#/jobs?repo=mozilla-central&searchStr=linux%2Cx64%2Copt%2Cmochitests%2Cwith%2Cfission%2Cenabled%2Ctest-linux64%2Fopt-mochitest-devtools-chrome-fis-e10s-5%2Cm-fis%28dt5%29&group_state=expanded&fromchange=f529796a87c2e6ef2db08d7d8348500901ec4da9&tochange=8d251dd4a9bebaa3e72a23d795122d4b12097ffc

Can you please take a look at it?

Comment 14

[Intermittent Failures Robot]

19 failures in 1088 pushes (0.017 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 19

Platform breakdown:

• linux64: 14
• windows10-64: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1535451&startday=2019-08-30&endday=2019-08-30&tree=all

Comment 15

[Andreea Pavel [:apavel]]

This is not fixed: https://treeherder.mozilla.org/#/jobs?repo=mozilla-central&selectedJob=264421873&resultStatus=pending%2Crunning%2Csuccess%2Ctestfailed%2Cbusted%2Cexception&classifiedState=unclassified&searchStr=linux%2Cx64%2Copt%2Cmochitests%2Cwith%2Cfission%2Cenabled%2Ctest-linux64%2Fopt-mochitest-devtools-chrome-fis-e10s-3%2Cm-fis%28dt3%29

Nicolas should we just disable the test?

Comment 16

[Intermittent Failures Robot]

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

This is the #21 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:

• mozilla-central: 73

Platform breakdown:

• linux64: 52
• windows10-64: 21

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

Comment 17

[Nicolas Chevobbe [:nchevobbe]]

Nicolas should we just disable the test?

Let's do that for now, I'm not sure what's happening.

Comment 18

[Bogdan Tara[:bogdan_tara | bogdant]]

https://hg.mozilla.org/releases/mozilla-beta/rev/59e90b5a43fa

Comment 19

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/278c07df138f
Skip browser_webconsole_trackingprotection_errors.js on fission. r=nchevobbe

Comment 20

[Dorel Luca [:dluca]]

https://hg.mozilla.org/mozilla-central/rev/278c07df138f

Comment 21

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform breakdown:

• linux64: 1

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

Comment 22

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 2

Platform breakdown:

• windows7-32-shippable: 1
• windows7-32-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1535451&startday=2019-12-23&endday=2019-12-29&tree=all

Comment 23

[Chris Peterson [:cpeterson]]

This browser_webconsole_trackingprotection_errors.js test is still disabled for Fission.

DevTools bugs don't need to block Fission mochitests milestone (M4.1) because there is a lot of Fission DevTools work remaining. Tracking for Fission Nightly (M6).

Comment 24

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• windows7-32-shippable: 1

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

Comment 25

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform breakdown:

• windows7-32-shippable: 1

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

Comment 26

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• linux1804-64: 1
• macosx1014-64: 1

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

Comment 27

[Chris Peterson [:cpeterson]]

This web console test is still skipped for Fission:

https://searchfox.org/mozilla-central/source/devtools/client/webconsole/test/browser/_webconsole.ini#371-372

[browser_webconsole_trackingprotection_errors.js]
skip-if = fission #Bug 1535451

Adding dt-fission whiteboard tag to DevTools bugs that mention Fission or block Fission meta bugs but don't already have a dt-fission whiteboard tag.

Comment 28

[Chris Peterson [:cpeterson]]

Moving dt-fission bugs to Fission MVP.

Comment 29

[Chris Peterson [:cpeterson]]

"dt-fission" bugs don't block Fission MVP.

Comment 30

[Alexandre Poirot [:ochameau]]

This seem to fails on fission because of this code:
https://searchfox.org/mozilla-central/rev/4fe6dea30b40a6c34616d57ac0b9444e677c5be2/toolkit/components/antitracking/ContentBlockingNotifier.cpp#207,223
We emit console service notifications with the innerWindowID of the top BrowsingContext/WindowGlobal.
This ultimately ends up confusing this DevTools code:
https://searchfox.org/mozilla-central/rev/364aa1d41eb9b5ea583fa6cedc56fb5343e3514f/devtools/server/actors/resources/error-messages.js#88-90
message's innerWindowId ends up being the top level WindowGlobal's one instead of the iframe one.
The Error Message resource listener that receives the message is the iframe one, and it is only dispatched in the iframe's process.
So the top document's one isn't receiving the message, and even if it was, it would ignore it as we only accept same-process iframe messages.

Comment 31

[Alexandre Poirot [:ochameau]]

Note that browser_webconsole_warning_group_storage_isolation.js is being disabled on fission for a similar reason. (bug 1653057)

Comment 32

[Tim Huang[:timhuang]]

Attachment: Bug 1535451 - Enable browser_webconsole_trackingprotection_errors.js test on Fission. r?nchevobbe!

Depends on D141096

Comment 33

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/65ee1cad1d7c
Enable browser_webconsole_trackingprotection_errors.js test on Fission. r=nchevobbe

Comment 34

[Cristian Tuns]

https://hg.mozilla.org/mozilla-central/rev/65ee1cad1d7c

Comment 35

[Tim Huang[:timhuang]]

We can close this bug.