Closed Bug 1636348 Opened 6 years ago Closed 6 years ago

Intermittent devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_navigate_to_url.js | A promise chain failed to handle a rejection: can't access property "send", this.transport is null - stack: send@resource://devtools/server/devtools-se

Categories

(DevTools :: about:debugging, defect, P5)

Product:
DevTools
Component:
about:debugging
Type:
defect

Tracking

(firefox-esr68 unaffected, firefox76 unaffected, firefox77 unaffected, firefox78 fixed)

Status:
RESOLVED FIXED
Milestone:
Firefox 78
Tracking Flags:
Tracking Status
firefox-esr68 --- unaffected
firefox76 --- unaffected
firefox77 --- unaffected
firefox78 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: mtigley)

References

(Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [retriggered])

Attachments

(1 file)

Bug 1636348 - Ensure the connection between the about:devtools-toolbox's client and server is closed. r=jdescottes
47 bytes, text/x-phabricator-request
Details | Review

Filed by: rmaries [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=301340584&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/aOV6qfWGQAaGVJailG9xSw/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/aOV6qfWGQAaGVJailG9xSw/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2020-05-08T01:11:59.016Z] 01:11:59 INFO - TEST-START | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_navigate_to_url.js
[task 2020-05-08T01:12:10.100Z] 01:12:10 INFO - TEST-PASS | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_navigate_to_url.js | The main process DevToolsServer has no pending connection when the test ends -
[task 2020-05-08T01:12:10.103Z] 01:12:10 INFO - Console message: [JavaScript Error: "TypeError: can't access property "send", this.transport is null" {file: "resource://devtools/server/devtools-server-connection.js" line: 91}]
[task 2020-05-08T01:12:10.103Z] 01:12:10 INFO - Console message: [JavaScript Error: "TypeError: can't access property "send", this.transport is null" {file: "resource://devtools/server/devtools-server-connection.js" line: 91}]
[task 2020-05-08T01:12:10.103Z] 01:12:10 INFO - Console message: [JavaScript Error: "TypeError: can't access property "send", this.transport is null" {file: "resource://devtools/server/devtools-server-connection.js" line: 91}]
[task 2020-05-08T01:12:10.103Z] 01:12:10 INFO - Console message: [JavaScript Error: "TypeError: can't access property "send", this.transport is null" {file: "resource://devtools/server/devtools-server-connection.js" line: 91}]
[task 2020-05-08T01:12:10.103Z] 01:12:10 INFO - Buffered messages finished
[task 2020-05-08T01:12:10.110Z] 01:12:10 INFO - TEST-UNEXPECTED-FAIL | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_navigate_to_url.js | A promise chain failed to handle a rejection: can't access property "send", this.transport is null - stack: send@resource://devtools/server/devtools-server-connection.js:91:5
[task 2020-05-08T01:12:10.110Z] 01:12:10 INFO - writeError@resource://devtools/shared/protocol/Actor.js:98:15
[task 2020-05-08T01:12:10.110Z] 01:12:10 INFO - generateRequestHandlers/</handler/</<@resource://devtools/shared/protocol/Actor.js:187:30
[task 2020-05-08T01:12:10.110Z] 01:12:10 INFO - promise callback*generateRequestHandlers/</handler/<@resource://devtools/shared/protocol/Actor.js:187:14
[task 2020-05-08T01:12:10.110Z] 01:12:10 INFO - _queueResponse@resource://devtools/shared/protocol/Actor.js:107:28
[task 2020-05-08T01:12:10.111Z] 01:12:10 INFO - handler@resource://devtools/shared/protocol/Actor.js:183:14
[task 2020-05-08T01:12:10.111Z] 01:12:10 INFO - onPacket@resource://devtools/server/devtools-server-connection.js:380:58
[task 2020-05-08T01:12:10.111Z] 01:12:10 INFO - send/<@resource://devtools/shared/transport/local-transport.js:68:25
[task 2020-05-08T01:12:10.111Z] 01:12:10 INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:103:22

Micah, could you take a look?

Flags: needinfo?(mtigley)
Summary: Perma TV tier 2 devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_navigate_to_url.js | A promise chain failed to handle a rejection: can't access property "send", this.transport is null - stack: send@resource://devtools/server/devtools-se → Intermittent devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_navigate_to_url.js | A promise chain failed to handle a rejection: can't access property "send", this.transport is null - stack: send@resource://devtools/server/devtools-se

Set release status flags based on info from the regressing bug 1617237

status-firefox76: --- → unaffected
status-firefox77: --- → unaffected
status-firefox78: --- → affected

Thanks! I'll investigate.

Assignee: nobody → mtigley
Status: NEW → ASSIGNED
Flags: needinfo?(mtigley)
Attachment #9146898 - Attachment description: Bug 1636348 - Ensure the connection between the about:devtools' client and server is closed. r=jdescottes → Bug 1636348 - Ensure the connection between the about:devtools-toolbox's client and server is closed. r=jdescottes
Whiteboard: [retriggered]
Pushed by mtigley@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/60e4890ac49a Ensure the connection between the about:devtools-toolbox's client and server is closed. r=jdescottes

Backed out changeset 60e4890ac49a (bug 1636348) for browser_aboutdebugging_navigate_to_url.js failures

Push with failures: https://treeherder.mozilla.org/#/jobs?repo=autoland&fromchange=60e4890ac49ae748f0aeec1d6b3d9c72e4c97f39&searchStr=debug-mochitest-devtools&tochange=ea3b5d741549fbd33960c1ff77e3a16d738b4117&selectedTaskRun=OjpfdRqPS3CpOvPy-RLDQg-0

Backout link: https://hg.mozilla.org/integration/autoland/rev/ea3b5d741549fbd33960c1ff77e3a16d738b4117

Failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=301784442&repo=autoland&lineNumber=6220

...
[task 2020-05-11T20:32:47.046Z] 20:32:47     INFO - GECKO(5520) | [Parent 6864: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (0000021BD954CC00) [pid = 6864] [serial = 6] [outer = 0000000000000000] [url = chrome://extensions/content/dummy.xhtml]
[task 2020-05-11T20:32:47.047Z] 20:32:47     INFO - GECKO(5520) | [Parent 6864: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (0000021BD7DDC430) [pid = 6864] [serial = 5] [outer = 0000000000000000] [url = chrome://extensions/content/dummy.xhtml]
[task 2020-05-11T20:32:47.047Z] 20:32:47     INFO - GECKO(5520) | [Parent 6864: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (0000021BD7DDC090) [pid = 6864] [serial = 9] [outer = 0000000000000000] [url = resource://gre-resources/hiddenWindow.html]
[task 2020-05-11T20:32:47.047Z] 20:32:47     INFO - GECKO(5520) | [Parent 6864: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0000021C00D06000) [pid = 6864] [serial = 662] [outer = 0000000000000000] [url = about:blank]
[task 2020-05-11T20:32:47.047Z] 20:32:47     INFO - GECKO(5520) | [Parent 6864: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (0000021BD7DDF8C0) [pid = 6864] [serial = 7] [outer = 0000000000000000] [url = about:blank]
[task 2020-05-11T20:32:47.066Z] 20:32:47     INFO - GECKO(5520) | [Parent 6864: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0000021BD7D65C00) [pid = 6864] [serial = 11] [outer = 0000000000000000] [url = resource://gre-resources/hiddenWindow.html]
[task 2020-05-11T20:32:47.066Z] 20:32:47     INFO - GECKO(5520) | [Parent 6864: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0000021BDF38C800) [pid = 6864] [serial = 440] [outer = 0000000000000000] [url = about:blank]
[task 2020-05-11T20:32:47.066Z] 20:32:47     INFO - GECKO(5520) | [Parent 6864: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0000021BE590B000) [pid = 6864] [serial = 434] [outer = 0000000000000000] [url = about:debugging]
[task 2020-05-11T20:32:47.066Z] 20:32:47     INFO - GECKO(5520) | [Parent 6864, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/netwerk/base/nsNetUtil.cpp, line 2293
[task 2020-05-11T20:32:47.066Z] 20:32:47     INFO - GECKO(5520) | [Parent 6864, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/caps/ContentPrincipal.cpp, line 398
[task 2020-05-11T20:32:47.066Z] 20:32:47     INFO - GECKO(5520) | [Parent 6864, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/caps/ContentPrincipal.cpp, line 423
[task 2020-05-11T20:32:47.110Z] 20:32:47     INFO - GECKO(5520) | [Parent 6864, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-05-11T20:32:47.323Z] 20:32:47     INFO - TEST-INFO | Main app process: exit 0
[task 2020-05-11T20:32:47.324Z] 20:32:47     INFO - TEST-INFO | Confirming we saw 352 DOCSHELL created and 352 destroyed log strings.
[task 2020-05-11T20:32:47.324Z] 20:32:47     INFO - TEST-INFO | Confirming we saw 956 DOMWINDOW created and 956 destroyed log strings.
[task 2020-05-11T20:32:47.324Z] 20:32:47    ERROR - TEST-UNEXPECTED-FAIL | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_navigate_to_url.js | leaked 2 window(s) until shutdown [url = about:debugging]
[task 2020-05-11T20:32:47.325Z] 20:32:47    ERROR - TEST-UNEXPECTED-FAIL | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_navigate_to_url.js | leaked 2 window(s) until shutdown [url = about:devtools-toolbox?type=tab&id=557]
[task 2020-05-11T20:32:47.325Z] 20:32:47    ERROR - TEST-UNEXPECTED-FAIL | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_navigate_to_url.js | leaked 1 window(s) until shutdown [url = chrome://devtools/content/inspector/index.xhtml]
[task 2020-05-11T20:32:47.326Z] 20:32:47    ERROR - TEST-UNEXPECTED-FAIL | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_navigate_to_url.js | leaked 1 window(s) until shutdown [url = about:blank]
[task 2020-05-11T20:32:47.326Z] 20:32:47     INFO - TEST-INFO | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_navigate_to_url.js | windows(s) leaked: [pid = 6864] [serial = 432], [pid = 6864] [serial = 435], [pid = 6864] [serial = 434], [pid = 6864] [serial = 439], [pid = 6864] [serial = 438], [pid = 6864] [serial = 440]
[task 2020-05-11T20:32:47.327Z] 20:32:47     INFO - TEST-INFO | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_navigate_to_url.js | This test created 1 hidden window(s)
[task 2020-05-11T20:32:47.327Z] 20:32:47     INFO - TEST-INFO | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_navigate_to_url.js | This test created 1 hidden docshell(s)
[task 2020-05-11T20:32:47.327Z] 20:32:47     INFO - TEST-INFO | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_addons_debug_console.js | This test created 1 hidden window(s)
[task 2020-05-11T20:32:47.328Z] 20:32:47     INFO - TEST-INFO | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_addons_debug_console.js | This test created 1 hidden docshell(s)
[task 2020-05-11T20:32:47.328Z] 20:32:47     INFO - TEST-INFO | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_addons_debug_debugger.js | This test created 1 hidden window(s)
[task 2020-05-11T20:32:47.329Z] 20:32:47     INFO - TEST-INFO | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_addons_debug_debugger.js | This test created 1 hidden docshell(s)
...
Flags: needinfo?(mtigley)

It looks like the tabs opened from running the test are still around even after it's finished. I can take a look.

Flags: needinfo?(mtigley)
Pushed by mtigley@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/d4b7b9d5d1fb Ensure the connection between the about:devtools-toolbox's client and server is closed. r=jdescottes

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

Status: ASSIGNED → RESOLVED
Closed: 6 years ago
status-firefox78: affected → fixed
Resolution: --- → FIXED
Target Milestone: --- → Firefox 78
Has Regression Range: --- → yes
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: