1842800 - Intermittent dom/base/test/browser_use_counters.js | single tracking bug

Status: RESOLVED FIXED

(Core :: DOM: Core & HTML, defect)

Description

[Treeherder Bug Filer]

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

---

[task 2023-07-11T10:03:51.515Z] 10:03:51     INFO - TEST-START | dom/base/test/browser_use_counters.js
[task 2023-07-11T10:03:51.543Z] 10:03:51     INFO - TEST-INFO | started process screenshot
[task 2023-07-11T10:03:51.652Z] 10:03:51     INFO - TEST-INFO | screenshot: exit 0
[task 2023-07-11T10:03:51.661Z] 10:03:51     INFO - Buffered messages logged at 10:03:51
[task 2023-07-11T10:03:51.661Z] 10:03:51     INFO - Entering test bound test_initialize
[task 2023-07-11T10:03:51.662Z] 10:03:51     INFO - canRecord for content: false
[task 2023-07-11T10:03:51.662Z] 10:03:51     INFO - Leaving test bound test_initialize
[task 2023-07-11T10:03:51.662Z] 10:03:51     INFO - Entering test bound 
[task 2023-07-11T10:03:51.663Z] 10:03:51     INFO - checking file_use_counter_svg_getElementById.svg (iframe)
[task 2023-07-11T10:03:51.663Z] 10:03:51     INFO - Buffered messages finished
[task 2023-07-11T10:03:51.667Z] 10:03:51     INFO - TEST-UNEXPECTED-FAIL | dom/base/test/browser_use_counters.js | uncaught exception - NS_ERROR_UNEXPECTED: Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIScriptSecurityManager.getLoadContextContentPrincipal] at construct@chrome://global/content/elements/browser-custom-element.js:1024:38
[task 2023-07-11T10:03:51.667Z] 10:03:51     INFO - connectedCallback@chrome://global/content/elements/browser-custom-element.js:330:12
[task 2023-07-11T10:03:51.667Z] 10:03:51     INFO - _insertBrowser@chrome://browser/content/tabbrowser.js:2380:24
[task 2023-07-11T10:03:51.667Z] 10:03:51     INFO - addTab@chrome://browser/content/tabbrowser.js:2780:16
[task 2023-07-11T10:03:51.667Z] 10:03:51     INFO - addTab@resource://testing-common/BrowserTestUtils.sys.mjs:2659:23
[task 2023-07-11T10:03:51.667Z] 10:03:51     INFO - @chrome://mochitests/content/browser/dom/base/test/browser_use_counters.js:157:35
[task 2023-07-11T10:03:51.667Z] 10:03:51     INFO - handleTask@chrome://mochikit/content/browser-test.js:1131:26
[task 2023-07-11T10:03:51.667Z] 10:03:51     INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1203:18
[task 2023-07-11T10:03:51.667Z] 10:03:51     INFO - async*Tester_execTest@chrome://mochikit/content/browser-test.js:1345:14
[task 2023-07-11T10:03:51.667Z] 10:03:51     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1120:14
[task 2023-07-11T10:03:51.667Z] 10:03:51     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1056:13
[task 2023-07-11T10:03:51.667Z] 10:03:51     INFO - 
[task 2023-07-11T10:03:51.667Z] 10:03:51     INFO - Stack trace:
[task 2023-07-11T10:03:51.667Z] 10:03:51     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:simpletestOnerror:2003
[task 2023-07-11T10:03:51.667Z] 10:03:51     INFO - chrome://browser/content/tabbrowser.js:_insertBrowser:2380
[task 2023-07-11T10:03:51.667Z] 10:03:51     INFO - chrome://browser/content/tabbrowser.js:addTab:2780
[task 2023-07-11T10:03:51.667Z] 10:03:51     INFO - resource://testing-common/BrowserTestUtils.sys.mjs:addTab:2659
[task 2023-07-11T10:03:51.668Z] 10:03:51     INFO - chrome://mochitests/content/browser/dom/base/test/browser_use_counters.js:null:157
[task 2023-07-11T10:03:51.668Z] 10:03:51     INFO - chrome://mochikit/content/browser-test.js:handleTask:1131
[task 2023-07-11T10:03:51.668Z] 10:03:51     INFO - chrome://mochikit/content/browser-test.js:_runTaskBasedTest:1203
[task 2023-07-11T10:03:51.668Z] 10:03:51     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1345
[task 2023-07-11T10:03:51.668Z] 10:03:51     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1120
[task 2023-07-11T10:03:51.668Z] 10:03:51     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1056
[task 2023-07-11T10:03:51.669Z] 10:03:51     INFO - GECKO(4256) | JavaScript error: chrome://global/content/elements/browser-custom-element.js, line 1024: NS_ERROR_UNEXPECTED: Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIScriptSecurityManager.getLoadContextContentPrincipal]
[task 2023-07-11T10:03:51.669Z] 10:03:51     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - TEST-UNEXPECTED-FAIL | dom/base/test/browser_use_counters.js | uncaught exception - TypeError: can't access property "originNoSuffix", newBrowser.contentPrincipal is null at updateCurrentBrowser@chrome://browser/content/tabbrowser.js:1142:1
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - _setupEventListeners/<@chrome://browser/content/tabbrowser.js:5935:16
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - set selectedIndex@chrome://global/content/elements/tabbox.js:236:14
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - set selectedPanel@chrome://global/content/elements/tabbox.js:246:54
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - set selectedIndex@chrome://global/content/elements/tabbox.js:599:9
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - set selectedItem@chrome://global/content/elements/tabbox.js:617:35
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - set selectedTab@chrome://global/content/elements/tabbox.js:80:11
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - set selectedTab@chrome://browser/content/tabbrowser.js:359:7
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - @chrome://mochitests/content/browser/dom/base/test/browser_use_counters.js:158:5
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - handleTask@chrome://mochikit/content/browser-test.js:1131:26
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1203:18
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - async*Tester_execTest@chrome://mochikit/content/browser-test.js:1345:14
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1120:14
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1056:13
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - 
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - Stack trace:
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:simpletestOnerror:2003
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - chrome://global/content/elements/tabbox.js:set selectedIndex:236
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - chrome://global/content/elements/tabbox.js:set selectedPanel:246
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - chrome://global/content/elements/tabbox.js:set selectedIndex:599
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - chrome://global/content/elements/tabbox.js:set selectedItem:617
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - chrome://global/content/elements/tabbox.js:set selectedTab:80
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - chrome://browser/content/tabbrowser.js:set selectedTab:359
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - chrome://mochitests/content/browser/dom/base/test/browser_use_counters.js:null:158
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - chrome://mochikit/content/browser-test.js:handleTask:1131
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - chrome://mochikit/content/browser-test.js:_runTaskBasedTest:1203
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1345
[task 2023-07-11T10:03:51.674Z] 10:03:51     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1120
[task 2023-07-11T10:03:51.677Z] 10:03:51     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1056
[task 2023-07-11T10:03:51.677Z] 10:03:51     INFO - GECKO(4256) | JavaScript error: chrome://browser/content/tabbrowser.js, line 1142: TypeError: can't access property "originNoSuffix", newBrowser.contentPrincipal is null
[task 2023-07-11T10:03:51.677Z] 10:03:51     INFO - Console message: [JavaScript Error: "NS_ERROR_UNEXPECTED: Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIScriptSecurityManager.getLoadContextContentPrincipal]" {file: "chrome://global/content/elements/browser-custom-element.js" line: 1024}]
[task 2023-07-11T10:03:51.677Z] 10:03:51     INFO - construct@chrome://global/content/elements/browser-custom-element.js:1024:38
[task 2023-07-11T10:03:51.677Z] 10:03:51     INFO - connectedCallback@chrome://global/content/elements/browser-custom-element.js:330:12
<...>

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows11-64-2009-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1842800&startday=2023-07-10&endday=2023-07-16&tree=all

Comment 2

[Intermittent Failures Robot]

56 failures in 608 pushes (0.092 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 21
• mozilla-central: 21
• try: 14

Platform and build breakdown:

• macosx1015-64-qr: 16
  • debug: 12
  • opt: 4
• linux1804-64-asan-qr: 1
  • opt: 1
• linux1804-64-qr: 2
  • opt: 2
• windows11-32-2009-qr: 4
  • opt: 4
• windows11-64-2009-qr: 3
  • opt: 3
• macosx1015-64-shippable-qr: 7
  • opt: 7
• macosx1100-64-shippable-qr: 7
  • opt: 7
• windows11-64-2009-shippable-qr: 7
  • opt: 7
• windows11-64-2009-ccov-qr: 2
  • opt: 2
• windows11-32-2009-shippable-qr: 2
  • opt: 2
• linux1804-64-shippable-qr: 1
  • opt: 1
• macosx1015-64-devedition-qr: 2
  • opt: 2
• linux1804-64-devedition-qr: 1
  • opt: 1
• windows11-64-2009-devedition-qr: 1
  • opt: 1

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

Comment 3

[Natalia Csoregi [:nataliaCs]]

Update

There have been 65 failures within the last 7 days:

• 1 failure on Linux 18.04 x64 WebRender asan opt
• 2 failures on Linux 18.04 x64 WebRender debug/opt
• 2 failures on Linux 18.04 x64 WebRender Shippable opt
• 1 failure on Linux 18.04 x64 WebRender tsan opt
• 25 failures on OS X 10.15 WebRender opt/debug
• 6 failures on OS X 10.15 WebRender Shippable opt
• 6 failures on OS X 11 WebRender Shippable opt
• 3 failures on Windows 11 x86 22H2 WebRender opt
• 2 failures on Windows 11 x86 22H2 WebRender Shippable opt
• 2 failures on Windows 11 x64 22H2 CCov WebRender opt
• 8 failures on Windows 11 x64 22H2 WebRender opt
• 7 failures on Windows 11 x64 22H2 WebRender Shippable opt

Recent log: https://treeherder.mozilla.org/logviewer?job_id=426251167&repo=autoland&lineNumber=6925

Comment 4

[Iulian Moraru]

All the failures here that have this failure line TEST-UNEXPECTED-FAIL | dom/base/test/browser_use_counters.js | Test timed out -(~50 failures) should have been classified with fix by commit.
But this is failing frequently now with TEST-UNEXPECTED-FAIL | dom/base/test/browser_use_counters.js | top level destroyed document counts are correct - Got 113, expected 114.
First occurrence of this new failure line seems to be here, but that push doesn't really seem to be the cause of this.

Hi Emilio! Can you please take a look at this? I know that the backfills and retriggers don't point at Bug 1845779 to be the cause, but can it actually be regressed by that bug?
Thank you!

Comment 5

[Intermittent Failures Robot]

28 failures in 732 pushes (0.038 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 25
• mozilla-central: 3

Platform and build breakdown:

• macosx1015-64-qr: 18
  • debug: 6
  • opt: 12
• linux1804-64-tsan-qr: 1
  • opt: 1
• linux1804-64-qr: 2
  • debug: 1
  • opt: 1
• windows11-64-2009-ccov-qr: 1
  • opt: 1
• macosx1015-64-shippable-qr: 3
  • opt: 3
• macosx1100-64-shippable-qr: 2
  • opt: 2
• windows11-64-2009-qr: 1
  • debug: 1

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

Comment 6

[Emilio Cobos Álvarez (:emilio)]

Attachment: Bug 1842800 - Give the use-counters-in-bfcache test some slack.

Comment 7

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/ce1e0ca94418
Give the use-counters-in-bfcache test some slack.

Comment 8

[Cosmin Sabou [:CosminS]]

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

Comment 9

[Intermittent Failures Robot]

105 failures in 3076 pushes (0.034 failures/push) were associated with this bug in the last 7 days.

This is the #5 most frequent failure this week.

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

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

Repository breakdown:

• autoland: 63
• mozilla-central: 28
• try: 14

Platform and build breakdown:

• windows11-64-2009-qr: 11
  • debug: 5
  • opt: 6
• macosx1015-64-qr: 38
  • opt: 20
  • debug: 18
• windows11-32-2009-qr: 5
  • opt: 5
• linux1804-64-asan-qr: 1
  • opt: 1
• windows11-64-2009-shippable-qr: 8
  • opt: 8
• linux1804-64-shippable-qr: 3
  • opt: 3
• macosx1015-64-shippable-qr: 13
  • opt: 13
• macosx1100-64-shippable-qr: 12
  • opt: 12
• linux1804-64-qr: 4
  • opt: 3
  • debug: 1
• windows11-64-2009-ccov-qr: 3
  • opt: 3
• windows11-32-2009-shippable-qr: 2
  • opt: 2
• macosx1015-64-devedition-qr: 2
  • opt: 2
• linux1804-64-devedition-qr: 1
  • opt: 1
• windows11-64-2009-devedition-qr: 1
  • opt: 1
• linux1804-64-tsan-qr: 1
  • opt: 1

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