1750273 - Intermittent devtools/client/webconsole/test/browser/browser_jsterm_evaluation_context_selector_targets_update.js | A promise chain failed to handle a rejection: [Exception... "Method not implemented" nsresult: "0x80004001 (NS_ERROR_NOT_IMPLEMENTED)"

Status: RESOLVED FIXED

(DevTools :: Console, defect, P5)

Description

[Treeherder Bug Filer]

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

---

task 2022-01-14T17:49:30.139Z] 17:49:30     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_evaluation_context_selector_targets_update.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2022-01-14T17:49:30.139Z] 17:49:30     INFO - Buffered messages finished
[task 2022-01-14T17:49:30.143Z] 17:49:30     INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_jsterm_evaluation_context_selector_targets_update.js | A promise chain failed to handle a rejection: [Exception... "Method not implemented"  nsresult: "0x80004001 (NS_ERROR_NOT_IMPLEMENTED)"  location: "JS frame :: resource://devtools/client/framework/toolbox-host-manager.js :: switchHost :: line 278"  data: no] - stack: switchHost@resource://devtools/client/framework/toolbox-host-manager.js:278:15
[task 2022-01-14T17:49:30.143Z] 17:49:30     INFO - async*switchHostToTab@resource://devtools/client/framework/toolbox-host-manager.js:341:20
[task 2022-01-14T17:49:30.143Z] 17:49:30     INFO - _onMessage@resource://devtools/client/framework/toolbox-host-manager.js:181:14
[task 2022-01-14T17:49:30.143Z] 17:49:30     INFO - EventListener.handleEvent*create@resource://devtools/client/framework/toolbox-host-manager.js:100:47
[task 2022-01-14T17:49:30.143Z] 17:49:30     INFO - async*_createToolbox@resource://devtools/client/framework/devtools.js:674:35
[task 2022-01-14T17:49:30.143Z] 17:49:30     INFO - showToolbox@resource://devtools/client/framework/devtools.js:532:35
[task 2022-01-14T17:49:30.143Z] 17:49:30     INFO - showToolboxForTab@resource://devtools/client/framework/devtools.js:603:17
[task 2022-01-14T17:49:30.143Z] 17:49:30     INFO - async*openConsole@chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/head.js:886:35
[task 2022-01-14T17:49:30.143Z] 17:49:30     INFO - openNewTabWithIframesAndConsole@chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/head.js:123:10
[task 2022-01-14T17:49:30.143Z] 17:49:30     INFO - async*@chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/browser_jsterm_evaluation_context_selector_targets_update.js:18:21
[task 2022-01-14T17:49:30.143Z] 17:49:30     INFO - Async*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1130:34
[task 2022-01-14T17:49:30.143Z] 17:49:30     INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1170:11
[task 2022-01-14T17:49:30.143Z] 17:49:30     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:978:14
[task 2022-01-14T17:49:30.143Z] 17:49:30     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1041:13
[task 2022-01-14T17:49:30.143Z] 17:49:30     INFO - Rejection date: Fri Jan 14 2022 17:49:29 GMT+0000 (Greenwich Mean Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 271
[task 2022-01-14T17:49:30.143Z] 17:49:30     INFO - Stack trace:
[task 2022-01-14T17:49:30.143Z] 17:49:30     INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:271
[task 2022-01-14T17:49:30.144Z] 17:49:30     INFO - chrome://mochikit/content/browser-test.js:nextTest:625
[task 2022-01-14T17:49:30.144Z] 17:49:30     INFO - chrome://mochikit/content/browser-test.js:testScope/test_finish/<:1594
[task 2022-01-14T17:49:30.144Z] 17:49:30     INFO - chrome://mochikit/content/browser-test.js:run:1509
[task 2022-01-14T17:49:30.144Z] 17:49:30     INFO - GECKO(7500) | MEMORY STAT | vsize 2112787MB | vsizeMaxContiguous 65070727MB | residentFast 507MB | heapAllocated 192MB
[task 2022-01-14T17:49:30.145Z] 17:49:30     INFO - TEST-OK | devtools/client/webconsole/test/browser/browser_jsterm_evaluation_context_selector_targets_update.js | took 4183ms

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 9
• mozilla-central: 2

Platform and build breakdown:

• windows10-64-2004-qr: 8
  • debug: 8
• linux1804-64-qr: 1
  • opt: 1
• linux1804-64-shippable-qr: 1
  • opt: 1
• windows10-64-2004-ccov-qr: 1
  • opt: 1

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

Comment 2

[Iulian Moraru]

Update:
There have been 41 failures within the last 7 days:
• 5 failures on Linux 18.04 x64 WebRender asan opt
• 2 failures on Linux 18.04 x64 WebRender debug
• 6 failures on Linux 18.04 x64 WebRender opt
• 3 failures on Linux 18.04 x64 WebRender Shippable opt
• 2 failures on Windows 10 x64 2004 asan WebRender opt
• 1 failures on Windows 10 x64 2004 CCov WebRender opt
• 19 failures on Windows 10 x64 2004 WebRender debug
• 3 failures on Windows 10 x64 2004 WebRender Shippable opt
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=365166243&repo=autoland&lineNumber=21580

[task 2022-01-23T22:58:08.543Z] 22:58:08     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_evaluation_context_selector_targets_update.js | The context was set to the popup document - 
[task 2022-01-23T22:58:08.544Z] 22:58:08     INFO - Open a second popup and reload the original tab
[task 2022-01-23T22:58:08.545Z] 22:58:08     INFO - Console message: [JavaScript Warning: "Storage access automatically granted for origin https://test2.example.org on https://example.com."]
[task 2022-01-23T22:58:08.545Z] 22:58:08     INFO - Console message: [JavaScript Error: "Content Security Policy: The pages settings blocked the loading of a resource at inline (default-src)." {file: "chrome" line: 511}]
[task 2022-01-23T22:58:08.546Z] 22:58:08     INFO - Console message: [JavaScript Error: "Content Security Policy: The pages settings blocked the loading of a resource at inline (default-src)." {file: "chrome" line: 511}]
[task 2022-01-23T22:58:08.546Z] 22:58:08     INFO - Console message: [JavaScript Error: "Content Security Policy: The pages settings blocked the loading of a resource at inline (default-src)." {file: "chrome" line: 62}]
[task 2022-01-23T22:58:08.547Z] 22:58:08     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_evaluation_context_selector_targets_update.js | The web console did not crash - 
[task 2022-01-23T22:58:08.547Z] 22:58:08     INFO - Leaving test bound 
[task 2022-01-23T22:58:08.548Z] 22:58:08     INFO - Removing tab.
[task 2022-01-23T22:58:08.548Z] 22:58:08     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2022-01-23T22:58:08.549Z] 22:58:08     INFO - Got event: 'TabClose' on [object XULElement].
[task 2022-01-23T22:58:08.549Z] 22:58:08     INFO - Tab removed and finished closing
[task 2022-01-23T22:58:08.549Z] 22:58:08     INFO - Buffered messages logged at 22:58:08
[task 2022-01-23T22:58:08.550Z] 22:58:08     INFO - Removing tab.
[task 2022-01-23T22:58:08.551Z] 22:58:08     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2022-01-23T22:58:08.551Z] 22:58:08     INFO - Got event: 'TabClose' on [object XULElement].
[task 2022-01-23T22:58:08.552Z] 22:58:08     INFO - Tab removed and finished closing
[task 2022-01-23T22:58:08.552Z] 22:58:08     INFO - Removing tab.
[task 2022-01-23T22:58:08.553Z] 22:58:08     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2022-01-23T22:58:08.553Z] 22:58:08     INFO - Got event: 'TabClose' on [object XULElement].
[task 2022-01-23T22:58:08.554Z] 22:58:08     INFO - Tab removed and finished closing
[task 2022-01-23T22:58:08.554Z] 22:58:08     INFO - Console message: [JavaScript Error: "NotFoundError: No such JSWindowActor 'DevToolsFrame'"]
[task 2022-01-23T22:58:08.555Z] 22:58:08     INFO - Console message: [JavaScript Error: "NotFoundError: No such JSWindowActor 'DevToolsFrame'"]
[task 2022-01-23T22:58:08.555Z] 22:58:08     INFO - Console message: [JavaScript Error: "NotFoundError: No such JSWindowActor 'DevToolsFrame'"]
[task 2022-01-23T22:58:08.556Z] 22:58:08     INFO - Console message: [JavaScript Error: "NS_ERROR_NOT_IMPLEMENTED" {file: "resource://devtools/client/framework/toolbox-host-manager.js" line: 278}]
[task 2022-01-23T22:58:08.557Z] 22:58:08     INFO - Console message: [JavaScript Error: "Error: Connection closed, pending request to server0.conn88.windowGlobal2147483693/consoleActor4, type evaluateJSAsync failed
[task 2022-01-23T22:58:08.557Z] 22:58:08     INFO - 
[task 2022-01-23T22:58:08.557Z] 22:58:08     INFO - Request stack:
[task 2022-01-23T22:58:08.557Z] 22:58:08     INFO - request@resource://devtools/shared/protocol/Front.js:292:14
[task 2022-01-23T22:58:08.557Z] 22:58:08     INFO - generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:46:19
[task 2022-01-23T22:58:08.557Z] 22:58:08     INFO - execute/response<@resource://devtools/shared/commands/script/script-command.js:89:10
[task 2022-01-23T22:58:08.557Z] 22:58:08     INFO - execute@resource://devtools/shared/commands/script/script-command.js:72:28
[task 2022-01-23T22:58:08.557Z] 22:58:08     INFO - " {file: "resource://devtools/shared/protocol/Front.js" line: 106}]
[task 2022-01-23T22:58:08.558Z] 22:58:08     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_evaluation_context_selector_targets_update.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2022-01-23T22:58:08.558Z] 22:58:08     INFO - Buffered messages finished
[task 2022-01-23T22:58:08.561Z] 22:58:08     INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_jsterm_evaluation_context_selector_targets_update.js | A promise chain failed to handle a rejection: [Exception... "Method not implemented"  nsresult: "0x80004001 (NS_ERROR_NOT_IMPLEMENTED)"  location: "JS frame :: resource://devtools/client/framework/toolbox-host-manager.js :: switchHost :: line 278"  data: no] - stack: switchHost@resource://devtools/client/framework/toolbox-host-manager.js:278:15
[task 2022-01-23T22:58:08.561Z] 22:58:08     INFO - async*switchHostToTab@resource://devtools/client/framework/toolbox-host-manager.js:341:20
[task 2022-01-23T22:58:08.561Z] 22:58:08     INFO - _onMessage@resource://devtools/client/framework/toolbox-host-manager.js:181:14
[task 2022-01-23T22:58:08.561Z] 22:58:08     INFO - EventListener.handleEvent*create@resource://devtools/client/framework/toolbox-host-manager.js:100:47
[task 2022-01-23T22:58:08.561Z] 22:58:08     INFO - async*_createToolbox@resource://devtools/client/framework/devtools.js:674:35
[task 2022-01-23T22:58:08.561Z] 22:58:08     INFO - showToolbox@resource://devtools/client/framework/devtools.js:532:35
[task 2022-01-23T22:58:08.561Z] 22:58:08     INFO - showToolboxForTab@resource://devtools/client/framework/devtools.js:603:17
[task 2022-01-23T22:58:08.561Z] 22:58:08     INFO - async*openConsole@chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/head.js:886:35
[task 2022-01-23T22:58:08.561Z] 22:58:08     INFO - openNewTabWithIframesAndConsole@chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/head.js:123:10
[task 2022-01-23T22:58:08.561Z] 22:58:08     INFO - async*@chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/browser_jsterm_evaluation_context_selector_targets_update.js:18:21
[task 2022-01-23T22:58:08.561Z] 22:58:08     INFO - Async*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1130:34
[task 2022-01-23T22:58:08.561Z] 22:58:08     INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1170:11
[task 2022-01-23T22:58:08.562Z] 22:58:08     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:978:14
[task 2022-01-23T22:58:08.562Z] 22:58:08     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1041:13
[task 2022-01-23T22:58:08.562Z] 22:58:08     INFO - Rejection date: Sun Jan 23 2022 22:58:08 GMT+0000 (Greenwich Mean Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 271
[task 2022-01-23T22:58:08.562Z] 22:58:08     INFO - Stack trace:
[task 2022-01-23T22:58:08.562Z] 22:58:08     INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:271
[task 2022-01-23T22:58:08.562Z] 22:58:08     INFO - chrome://mochikit/content/browser-test.js:nextTest:625
[task 2022-01-23T22:58:08.562Z] 22:58:08     INFO - chrome://mochikit/content/browser-test.js:testScope/test_finish/<:1594
[task 2022-01-23T22:58:08.562Z] 22:58:08     INFO - chrome://mochikit/content/browser-test.js:run:1509
[task 2022-01-23T22:58:08.562Z] 22:58:08     INFO - GECKO(1564) | MEMORY STAT | vsize 2112756MB | vsizeMaxContiguous 65247857MB | residentFast 512MB | heapAllocated 194MB
[task 2022-01-23T22:58:08.563Z] 22:58:08     INFO - TEST-OK | devtools/client/webconsole/test/browser/browser_jsterm_evaluation_context_selector_targets_update.js | took 4813ms
[task 2022-01-23T22:58:08.563Z] 22:58:08     INFO - GECKO(1564) | [Child 7616: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 22ab0aac800 == 6 [pid = 7616] [id = 66]
[task 2022-01-23T22:58:08.564Z] 22:58:08     INFO - GECKO(1564) | [Child 7616: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 27 (22ab0310200) [pid = 7616] [serial = 162] [outer = 0]
[task 2022-01-23T22:58:08.564Z] 22:58:08     INFO - GECKO(1564) | [Child 7616: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 28 (22ab0ab1800) [pid = 7616] [serial = 163] [outer = 22ab0310200]
[task 2022-01-23T22:58:08.565Z] 22:58:08     INFO - GECKO(1564) | [Parent 8544, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x804B0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS): file /builds/worker/checkouts/gecko/toolkit/components/antitracking/URLQueryStringStripper.cpp:129
[task 2022-01-23T22:58:08.565Z] 22:58:08     INFO - checking window state
[task 2022-01-23T22:58:08.566Z] 22:58:08     INFO - GECKO(1564) | [Child 7616, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x804B0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS): file /builds/worker/checkouts/gecko/toolkit/components/antitracking/URLQueryStringStripper.cpp:129
[task 2022-01-23T22:58:08.567Z] 22:58:08     INFO - GECKO(1564) | [Child 7616, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x804B0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS): file /builds/worker/checkouts/gecko/toolkit/components/antitracking/URLQueryStringStripper.cpp:129
[task 2022-01-23T22:58:08.568Z] 22:58:08     INFO - TEST-START | devtools/client/webconsole/test/browser/browser_jsterm_file_load_save_keyboard_shortcut.js

Comment 3

[Intermittent Failures Robot]

44 failures in 3444 pushes (0.013 failures/push) were associated with this bug in the last 7 days.

This is the #27 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: 28
• mozilla-central: 13
• try: 3

Platform and build breakdown:

• linux1804-64-asan-qr: 5
  • opt: 5
• windows10-64-2004-qr: 20
  • debug: 20
• linux1804-64-qr: 9
  • opt: 7
  • debug: 2
• linux1804-64-shippable-qr: 3
  • opt: 3
• windows10-64-2004-ccov-qr: 1
  • opt: 1
• windows10-64-2004-asan-qr: 3
  • opt: 3
• windows10-64-2004-shippable-qr: 3
  • opt: 3

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

Comment 4

[Nicolas Chevobbe [:nchevobbe]]

I'll have a look

Comment 5

[Alexandre Poirot [:ochameau]]

I'm fixing something in bug 1751207 about switched-host-to-tab, this might be a source of troubles here.

Comment 6

[Intermittent Failures Robot]

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

This is the #37 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: 6
• autoland: 23
• try: 3

Platform and build breakdown:

• windows10-64-2004-qr: 12
  • debug: 12
• linux1804-64-asan-qr: 4
  • opt: 4
• linux1804-64-qr: 8
  • opt: 5
  • debug: 3
• linux1804-64-shippable-qr: 3
  • opt: 3
• windows10-64-2004-asan-qr: 5
  • opt: 5

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

Comment 7

[Nicolas Chevobbe [:nchevobbe]]

(In reply to Alexandre Poirot [:ochameau] from comment #5)

I'm fixing something in bug 1751207 about switched-host-to-tab, this might be a source of troubles here.

Looks like something else, Bug 1751207 is resolved but we still see this failure

Comment 8

[Nicolas Chevobbe [:nchevobbe]]

Attachment: Bug 1750273 - [devtools] Fix intermittent failures in browser_jsterm_evaluation_context_selector_targets_update.js. r=bomsy.

Wait for "host-changed" events in the test so things are settled when the
toolbox gets destroyed.

Comment 9

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/846b8458f164
[devtools] Fix intermittent failures in browser_jsterm_evaluation_context_selector_targets_update.js. r=bomsy.

Comment 10

[Cristina Cozmuta (:CrissCozmuta)]

https://hg.mozilla.org/mozilla-central/rev/846b8458f164

Comment 11

[Intermittent Failures Robot]

26 failures in 3165 pushes (0.008 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 15
• try: 3
• mozilla-central: 8

Platform and build breakdown:

• windows10-64-2004-qr: 7
  • debug: 6
  • opt: 1
• linux1804-64-qr: 4
  • opt: 3
  • debug: 1
• windows10-64-2004-ccov-qr: 4
  • opt: 4
• windows10-64-2004-asan-qr: 5
  • opt: 5
• linux1804-64-asan-qr: 1
  • opt: 1
• macosx1015-64-qr: 3
  • debug: 2
  • opt: 1
• linux1804-64-shippable-qr: 1
  • opt: 1
• linux1804-64-devedition-qr: 1
  • opt: 1

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