1591960 - Intermittent devtools/client/inspector/markup/test/browser_markup_load_01.js | A promise chain failed to handle a rejection: Connection closed, pending request to server0.conn75.child1/domnode21, type getAllSelectors failed

Status: RESOLVED DUPLICATE of bug 1588773

(DevTools :: Inspector, defect, P2)

Description

[Treeherder Bug Filer]

Filed by: rmaries [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=273255960&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/M5tq5Hg_QXyre6aw1jOmAA/runs/0/artifacts/public/logs/live_backing.log

---

[task 2019-10-28T14:38:06.656Z] 14:38:06 INFO - TEST-START | devtools/client/inspector/markup/test/browser_markup_load_01.js
[task 2019-10-28T14:38:10.887Z] 14:38:10 INFO - TEST-PASS | devtools/client/inspector/markup/test/browser_markup_load_01.js | The markup view is rendering -
[task 2019-10-28T14:38:10.902Z] 14:38:10 INFO - TEST-INFO | started process screenshot
[task 2019-10-28T14:38:10.969Z] 14:38:10 INFO - TEST-INFO | screenshot: exit 0
[task 2019-10-28T14:38:10.977Z] 14:38:10 INFO - TEST-UNEXPECTED-FAIL | devtools/client/inspector/markup/test/browser_markup_load_01.js | A promise chain failed to handle a rejection: Connection closed, pending request to server0.conn75.child1/domnode21, type getAllSelectors failed
[task 2019-10-28T14:38:10.977Z] 14:38:10 INFO -
[task 2019-10-28T14:38:10.977Z] 14:38:10 INFO - Request stack:
[task 2019-10-28T14:38:10.977Z] 14:38:10 INFO - request@resource://devtools/shared/protocol/Front.js:232:14
[task 2019-10-28T14:38:10.977Z] 14:38:10 INFO - generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:49:19
[task 2019-10-28T14:38:10.977Z] 14:38:10 INFO - getAllSelectors@resource://devtools/shared/fronts/node.js:547:18
[task 2019-10-28T14:38:10.977Z] 14:38:10 INFO - findNodeFront@resource://devtools/shared/fronts/walker.js:523:48
[task 2019-10-28T14:38:10.977Z] 14:38:10 INFO - asyncinspectNode@resource://devtools/client/framework/devtools.js:736:46
[task 2019-10-28T14:38:10.977Z] 14:38:10 INFO - asyncinspectNode@chrome://devtools-startup/content/DevToolsShim.jsm:255:28
[task 2019-10-28T14:38:10.977Z] 14:38:10 INFO - inspectNode@chrome://browser/content/nsContextMenu.js:1032:39
[task 2019-10-28T14:38:10.977Z] 14:38:10 INFO - oncommand@chrome://browser/content/browser.xhtml:1:14
[task 2019-10-28T14:38:10.977Z] 14:38:10 INFO - - stack: destroy@resource://devtools/shared/protocol/Front.js:68:23
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - destroy@resource://devtools/shared/fronts/node.js:133:11
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - _releaseFront@resource://devtools/shared/fronts/walker.js:250:10
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - getMutations@resource://devtools/shared/fronts/walker.js:366:14
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - asynconMutations@resource://devtools/shared/fronts/walker.js:421:10
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - onPacket@resource://devtools/shared/protocol/Front.js:259:36
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - onPacket@resource://devtools/shared/client/debugger-client.js:593:13
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - send/<@resource://devtools/shared/transport/local-transport.js:70:25
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:111:22
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - DevToolsUtils.executeSoonexports.executeSoon@resource://devtools/shared/DevToolsUtils.js:62:21
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - send@resource://devtools/shared/transport/local-transport.js:58:21
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - send@resource://devtools/server/debugger-server-connection.js:89:20
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - receiveMessage@resource://devtools/shared/transport/child-transport.js:66:16
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - MessageListener.receiveMessage*_addListener@resource://devtools/shared/transport/child-transport.js:40:14
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - ready@resource://devtools/shared/transport/child-transport.js:57:10
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - connectToFrame/</onActorCreated<@resource://devtools/server/connectors/frame-connector.js:183:22
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:111:22
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - MessageListener.receiveMessagetrackMessageManager@resource://devtools/server/connectors/frame-connector.js:55:12
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - connectToFrame/<@resource://devtools/server/connectors/frame-connector.js:283:5
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - connectToFrame@resource://devtools/server/connectors/frame-connector.js:41:10
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - connect@resource://devtools/server/actors/targets/frame-proxy.js:65:35
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - asyncBrowserTabList.prototype._getActorForBrowser@resource://devtools/server/actors/webbrowser.js:368:16
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - BrowserTabList.prototype.getTab@resource://devtools/server/actors/webbrowser.js:410:21
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - onGetTab@resource://devtools/server/actors/root.js:368:35
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - onPacket@resource://devtools/server/debugger-server-connection.js:378:58
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - send/<@resource://devtools/shared/transport/local-transport.js:70:25
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:111:22
[task 2019-10-28T14:38:10.978Z] 14:38:10 INFO - DevToolsUtils.executeSoonexports.executeSoon@resource://devtools/shared/DevToolsUtils.js:62:21
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - send@resource://devtools/shared/transport/local-transport.js:58:21
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - send@resource://devtools/shared/protocol/Front.js:216:30
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - request@resource://devtools/shared/protocol/Front.js:234:10
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:49:19
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - getTab@resource://devtools/shared/fronts/root.js:363:30
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - createTargetForTab@resource://devtools/client/framework/target.js:100:28
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - asyncforTab@resource://devtools/client/framework/target.js:42:26
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - openToolboxForTab@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:473:38
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - openInspector@chrome://mochitests/content/browser/devtools/client/inspector/test/shared-head.js:33:25
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - openInspectorForURL@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:262:51
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - async*@chrome://mochitests/content/browser/devtools/client/inspector/markup/test/browser_markup_load_01.js:39:47
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - Tester_execTest/<@chrome://mochikit/content/browser-test.js:1067:34
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1102:11
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - nextTest/<@chrome://mochikit/content/browser-test.js:930:14
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:805:67
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - Rejection date: Mon Oct 28 2019 14:38:09 GMT+0000 (Greenwich Mean Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 265
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - Stack trace:
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:265
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1098
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1102
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:930
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:805
[task 2019-10-28T14:38:10.979Z] 14:38:10 INFO - Leaving test bound
[task 2019-10-28T14:38:11.774Z] 14:38:11 INFO - GECKO(5208) | --DOMWINDOW == 6 (0E481400) [pid = 4900] [serial = 165] [outer = 00000000] [url = http://example.com/browser/devtools/client/inspector/markup/test/doc_markup_links.html]
[task 2019-10-28T14:38:11.774Z] 14:38:11 INFO - GECKO(5208) | --DOMWINDOW == 5 (0C68A800) [pid = 4900] [serial = 170] [outer = 00000000] [url = about:blank]
[task 2019-10-28T14:38:11.837Z] 14:38:11 INFO - GECKO(5208) | --DOCSHELL 1A8A4C00 == 8 [pid = 5072] [id = {e4fd36e4-3d48-427b-9abf-7a9610999d4c}] [url = about:blank]
[task 2019-10-28T14:38:11.844Z] 14:38:11 INFO - GECKO(5208) | [Parent 5072, Main Thread] WARNING: Wrong button set to eContextMenu event?: 'mMessage != eContextMenu || mButton == ((mContextMenuTrigger == eNormal) ? MouseButton::eRight : MouseButton::eLeft)', file z:/build/build/src/obj-firefox/dist/include\mozilla/MouseEvents.h, line 246
[task 2019-10-28T14:38:11.884Z] 14:38:11 INFO - GECKO(5208) | --DOMWINDOW == 4 (012793A0) [pid = 4900] [serial = 166] [outer = 00000000] [url = http://example.com/browser/devtools/client/inspector/markup/test/doc_markup_links.html]
[task 2019-10-28T14:38:11.963Z] 14:38:11 INFO - Removing tab.
[task 2019-10-28T14:38:11.963Z] 14:38:11 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2019-10-28T14:38:11.986Z] 14:38:11 INFO - Got event: 'TabClose' on [object XULElement].
[task 2019-10-28T14:38:12.001Z] 14:38:12 INFO - GECKO(5208) | [Child 4900, Main Thread] WARNING: NS_ENSURE_TRUE(mDoneSetup) failed: file z:/build/build/src/editor/composer/nsEditingSession.cpp, line 1189
[task 2019-10-28T14:38:12.008Z] 14:38:12 INFO - GECKO(5208) | [Parent 5072, Main Thread] WARNING: '!inner', file z:/build/build/src/dom/ipc/JSWindowActorService.cpp, line 172
[task 2019-10-28T14:38:12.011Z] 14:38:12 INFO - Tab removed and finished closing
[task 2019-10-28T14:38:12.030Z] 14:38:12 INFO - GECKO(5208) | --DOMWINDOW == 27 (174F44C0) [pid = 5072] [serial = 1117] [outer = 00000000] [url = about:blank]
[task 2019-10-28T14:38:12.033Z] 14:38:12 INFO - GECKO(5208) | --DOMWINDOW == 26 (174F43A0) [pid = 5072] [serial = 1105] [outer = 00000000] [url = chrome://devtools/content/inspector/index.xhtml]
[task 2019-10-28T14:38:12.033Z] 14:38:12 INFO - GECKO(5208) | --DOMWINDOW == 25 (174F4280) [pid = 5072] [serial = 1101] [outer = 00000000] [url = about:devtools-toolbox]
[task 2019-10-28T14:38:12.033Z] 14:38:12 INFO - GECKO(5208) | --DOMWINDOW == 24 (174F4B80) [pid = 5072] [serial = 1109] [outer = 00000000] [url = chrome://devtools/content/inspector/markup/markup.xhtml]
[task 2019-10-28T14:38:12.033Z] 14:38:12 INFO - GECKO(5208) | --DOCSHELL 19FBEC00 == 7 [pid = 5072] [id = {2b9a984f-ddc4-4533-a3d9-11c0fb1bb9ae}] [url = chrome://devtools/content/inspector/index.xhtml]
[task 2019-10-28T14:38:12.052Z] 14:38:12 INFO - GECKO(5208) | [Parent 5072, Main Thread] WARNING: NS_ENSURE_TRUE(GetWrapper()) failed: file z:/build/build/src/dom/ipc/JSWindowActor.cpp, line 55

Comment 1

[Intermittent Failures Robot]

26 failures in 787 pushes (0.033 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 21
• mozilla-central: 5

Platform breakdown:

• windows7-32: 4
• windows10-64: 8
• linux64-shippable: 2
• linux64: 4
• windows10-64-shippable: 4
• windows7-32-shippable: 3
• linux64-ccov: 1

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

Comment 2

[Nicolas Chevobbe [:nchevobbe]]

Moving ni? to Julian as he's the assignee of Bug 1590050.

Comment 3

[Julian Descottes [:jdescottes]]

I will take a look.

I wonder if this is really a regression from my patch or just the intermittent Bug 1564886 that had its signature updated because of my change (was failing on querySelector, now fails on getAllSelectors).

It just had 3 failures very close to each other time wise https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2019-10-22&endday=2019-10-29&tree=trunk&bug=1564886
And then my bug landed and changed the signature.

Comment 4

[Julian Descottes [:jdescottes]]

Oh nevermind, the 3 failures associated to the old intermittent are already for "getAllSelectors".
The old bug was tagged for some reason instead of creating a new one but it really is coming from my patch.

Comment 5

[Julian Descottes [:jdescottes]]

Attachment: Bug 1591960 - Wait for inspectNode to finish in browser_markup_load_01.js

Comment 6

[Julian Descottes [:jdescottes]]

I think this should stop happening when Bug 1588773 lands. The code paths that leads to this error will no longer be used when this other bug lands, so that should hopefully fix this intermittent.

Comment 7

[Julian Descottes [:jdescottes]]

No failure since 1588773 reached autoland. Marking as duplicate.

Comment 8

[Intermittent Failures Robot]

35 failures in 865 pushes (0.04 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 9
• try: 22
• mozilla-central: 4

Platform breakdown:

• windows10-64-shippable: 4
• linux64-shippable: 3
• linux64: 10
• windows7-32: 5
• macosx1014-64-shippable: 1
• windows10-64: 8
• windows7-32-shippable: 1
• windows10-64-ccov: 1
• linux64-devedition: 1
• windows7-32-devedition: 1

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

Comment 9

[Intermittent Failures Robot]

61 failures in 4314 pushes (0.014 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: 30
• mozilla-central: 9
• try: 22

Platform breakdown:

• windows7-32: 9
• linux64: 14
• windows10-64: 16
• linux64-shippable: 5
• windows10-64-shippable: 8
• linux64-ccov: 1
• windows7-32-shippable: 4
• macosx1014-64-shippable: 1
• windows10-64-ccov: 1
• linux64-devedition: 1
• windows7-32-devedition: 1

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