1860158 - Intermittent devtools/client/webconsole/test/browser/browser_console_context_menu_entries.js | single tracking bug

Status: RESOLVED FIXED

(DevTools :: Console, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2023-10-19T18:53:39.391Z] 18:53:39     INFO - TEST-INFO | started process screencapture
[task 2023-10-19T18:53:39.515Z] 18:53:39     INFO - TEST-INFO | screencapture: exit 0
[task 2023-10-19T18:53:39.515Z] 18:53:39     INFO - Buffered messages logged at 18:52:09
[task 2023-10-19T18:53:39.516Z] 18:53:39     INFO - Entering test bound 
[task 2023-10-19T18:53:39.516Z] 18:53:39     INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/webconsole/test/browser/test-console.html
[task 2023-10-19T18:53:39.517Z] 18:53:39     INFO - Tab added and finished loading
[task 2023-10-19T18:53:39.517Z] 18:53:39     INFO - Buffered messages logged at 18:52:11
[task 2023-10-19T18:53:39.518Z] 18:53:39     INFO - Console message: [JavaScript Warning: "The Web Console logging API (console.log, console.info, console.warn, console.error) has been disabled by a script on this page."]
[task 2023-10-19T18:53:39.518Z] 18:53:39     INFO - Turn on network monitoring
[task 2023-10-19T18:53:39.519Z] 18:53:39     INFO - Reload the content window to produce a network log
[task 2023-10-19T18:53:39.519Z] 18:53:39     INFO - Buffered messages logged at 18:52:12
[task 2023-10-19T18:53:39.520Z] 18:53:39     INFO - Matched a message with text: "test-console.html", all messages received.
[task 2023-10-19T18:53:39.520Z] 18:53:39     INFO - Open and check the context menu for the network message
[task 2023-10-19T18:53:39.521Z] 18:53:39     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_console_context_menu_entries.js | The context menu is displayed on a network message - 
[task 2023-10-19T18:53:39.521Z] 18:53:39     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_console_context_menu_entries.js | The context menu has the expected entries for a network message - 
[task 2023-10-19T18:53:39.522Z] 18:53:39     INFO - Logging a text message in the content window
[task 2023-10-19T18:53:39.522Z] 18:53:39     INFO - Console message: [JavaScript Error: "AbortError: Actor 'SpecialPowers' destroyed before query 'Spawn' was resolved"]
[task 2023-10-19T18:53:39.523Z] 18:53:39     INFO - Buffered messages finished
[task 2023-10-19T18:53:39.523Z] 18:53:39     INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_console_context_menu_entries.js | Test timed out - 
[task 2023-10-19T18:53:39.524Z] 18:53:39     INFO - Clear the browser console output
[task 2023-10-19T18:53:39.524Z] 18:53:39     INFO - Browser console cleared
[task 2023-10-19T18:53:39.525Z] 18:53:39     INFO - Wait for all Browser Console targets to be attached
[task 2023-10-19T18:53:39.525Z] 18:53:39     INFO - Waiting 1 seconds.
[task 2023-10-19T18:53:39.525Z] 18:53:39     INFO - Close the Browser Console
[task 2023-10-19T18:53:39.526Z] 18:53:39     INFO - GECKO(3281) | [Parent 3281, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp:1710
[task 2023-10-19T18:53:39.568Z] 18:53:39     INFO - Browser Console closed
[task 2023-10-19T18:53:39.568Z] 18:53:39     INFO - Removing tab.
[task 2023-10-19T18:53:39.569Z] 18:53:39     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2023-10-19T18:53:39.585Z] 18:53:39     INFO - Got event: 'TabClose' on [object XULElement].
[task 2023-10-19T18:53:39.595Z] 18:53:39     INFO - Tab removed and finished closing
[task 2023-10-19T18:53:39.619Z] 18:53:39     INFO - GECKO(3281) | console.warn: "IGNORED REDUX ACTION:" "AUTOCOMPLETE_CLEAR"
[task 2023-10-19T18:53:39.653Z] 18:53:39     INFO - GECKO(3281) | [Parent 3281, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:371
[task 2023-10-19T18:53:39.657Z] 18:53:39     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_console_context_menu_entries.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2023-10-19T18:53:39.671Z] 18:53:39     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-10-19T18:53:39.674Z] 18:53:39     INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_console_context_menu_entries.js | A promise chain failed to handle a rejection: Actor 'SpecialPowers' destroyed before query 'Spawn' was resolved - stack: (No stack available.)
[task 2023-10-19T18:53:39.674Z] 18:53:39     INFO - Rejection date: Thu Oct 19 2023 18:52:12 GMT+0000 (Greenwich Mean Time) - false == true - {"filename":"resource://testing-common/PromiseTestUtils.sys.mjs","name":"assertNoUncaughtRejections","sourceId":589,"lineNumber":266,"columnNumber":14,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"nextTest","sourceId":554,"lineNumber":769,"columnNumber":29,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"chrome://mochikit/content/browser-test.js","name":"timeoutFn","sourceId":554,"lineNumber":1440,"columnNumber":18,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"chrome://mochikit/content/browser-test.js","name":"Tester_execTest","sourceId":554,"lineNumber":1382,"columnNumber":45,"sourceLine":"","asyncCause":"setTimeout handler","asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"nextTest/<","sourceId":554,"lineNumber":1123,"columnNumber":14,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/tests/SimpleTest/SimpleTest.js","name":"SimpleTest.waitForFocus/<","sourceId":580,"lineNumber":1058,"columnNumber":13,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":null,"formattedStack":"SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"formattedStack":"nextTest/<@chrome://mochikit/content/browser-test.js:1123:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"formattedStack":"setTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1382:45\nnextTest/<@chrome://mochikit/content/browser-test.js:1123:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"async*timeoutFn@chrome://mochikit/content/browser-test.js:1440:18\nsetTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1382:45\nnextTest/<@chrome://mochikit/content/browser-test.js:1123:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"nextTest@chrome://mochikit/content/browser-test.js:769:29\nasync*timeoutFn@chrome://mochikit/content/browser-test.js:1440:18\nsetTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1382:45\nnextTest/<@chrome://mochikit/content/browser-test.js:1123:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"formattedStack":"assertNoUncaughtRejections@resource://testing-common/PromiseTestUtils.sys.mjs:266:14\nnextTest@chrome://mochikit/content/browser-test.js:769:29\nasync*timeoutFn@chrome://mochikit/content/browser-test.js:1440:18\nsetTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1382:45\nnextTest/<@chrome://mochikit/content/browser-test.js:1123:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}}
[task 2023-10-19T18:53:39.674Z] 18:53:39     INFO - Stack trace:
[task 2023-10-19T18:53:39.674Z] 18:53:39     INFO - resource://testing-common/PromiseTestUtils.sys.mjs:assertNoUncaughtRejections:266
[task 2023-10-19T18:53:39.674Z] 18:53:39     INFO - chrome://mochikit/content/browser-test.js:nextTest:769
[task 2023-10-19T18:53:39.674Z] 18:53:39     INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1440
[task 2023-10-19T18:53:39.674Z] 18:53:39     INFO - setTimeout handler*chrome://mochikit/content/browser-test.js:Tester_execTest:1382
[task 2023-10-19T18:53:39.674Z] 18:53:39     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1123
[task 2023-10-19T18:53:39.674Z] 18:53:39     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1058
[task 2023-10-19T18:53:39.721Z] 18:53:39     INFO - GECKO(3281) | [Child 3317: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 10ddb5800 == 0 [pid = 3317] [id = 0] [url = http://example.com/browser/devtools/client/webconsole/test/browser/test-console.html]
[task 2023-10-19T18:53:39.767Z] 18:53:39     INFO - GECKO(3281) | [Child 3317: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (12376ac00) [pid = 3317] [serial = 4] [outer = 0] [url = http://example.com/browser/devtools/client/webconsole/test/browser/test-console.html]
[task 2023-10-19T18:53:39.768Z] 18:53:39     INFO - GECKO(3281) | [Child 3317: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (10633e3e0) [pid = 3317] [serial = 1] [outer = 0] [url = http://example.com/browser/devtools/client/webconsole/test/browser/test-console.html]
[task 2023-10-19T18:53:40.128Z] 18:53:40     INFO - GECKO(3281) | MEMORY STAT | vsize 8301MB | residentFast 514MB | heapAllocated 191MB
[task 2023-10-19T18:53:40.129Z] 18:53:40     INFO - TEST-OK | devtools/client/webconsole/test/browser/browser_console_context_menu_entries.js | took 90863ms
[task 2023-10-19T18:53:40.149Z] 18:53:40     INFO - GECKO(3281) | [Child 3287: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 10b8c9c00 == 1 [pid = 3287] [id = 9]
[task 2023-10-19T18:53:40.149Z] 18:53:40     INFO - GECKO(3281) | [Child 3287: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (10153e3e0) [pid = 3287] [serial = 24] [outer = 0]
[task 2023-10-19T18:53:40.150Z] 18:53:40     INFO - GECKO(3281) | [Child 3287: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (10b8ca400) [pid = 3287] [serial = 25] [outer = 10153e3e0]
[task 2023-10-19T18:53:40.172Z] 18:53:40     INFO - checking window state
[task 2023-10-19T18:53:40.203Z] 18:53:40     INFO - TEST-START | devtools/client/webconsole/test/browser/browser_console_context_menu_export_console_output.js

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• macosx1015-64-qr: 1
  • debug: 1

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

Comment 2

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

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

Comment 3

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=460351003&repo=mozilla-central

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• macosx1015-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1860158&startday=2024-05-27&endday=2024-06-02&tree=all

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3
• try: 1

Platform and build breakdown:

• macosx1015-64-qr: 4
  • debug: 4

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

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 7
• mozilla-central: 2
• try: 3

Platform and build breakdown:

• macosx1015-64-qr: 12
  • debug: 12

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

Comment 7

[Intermittent Failures Robot]

36 failures in 4116 pushes (0.009 failures/push) were associated with this bug in the last 7 days.

** 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: 24
• mozilla-central: 11
• try: 1

Platform and build breakdown:

• macosx1015-64-qr: 36
  • debug: 36

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

Comment 8

[Nicolas Chevobbe [:nchevobbe]]

Attachment: Bug 1860158 - [devtools] Fix intermittent failure on browser_console_context_menu_entries.js. r=#devtools-reviewers.

The intermittent seems to come from SpecialPowers actor being destroyed, possibly
because of the tab navigation.
Switch to a fetch to display the network message in the Browser Console so we
avoid the navigation.

Comment 9

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/050dede9a43c
[devtools] Fix intermittent failure on browser_console_context_menu_entries.js. r=devtools-reviewers,ochameau.

Comment 10

[pstanciu]

https://hg.mozilla.org/mozilla-central/rev/050dede9a43c

Comment 11

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 7
• mozilla-central: 8
• try: 2

Platform and build breakdown:

• macosx1015-64-qr: 17
  • debug: 17

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