Closed Bug 1651968 Opened 4 years ago Closed 4 years ago

High Frequency [Tier 2] devtools/client/framework/test/browser_tab_descriptor_fission.js | A promise chain failed to handle a rejection: Can not send request 'getWatcher' because front 'tabDescriptor' is already destroyed. - stack: generateRequestMethods/

Categories

(DevTools :: Framework, defect, P5)

Product:
DevTools
Component:
Framework
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=309170051&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/QRCV-mfCQ4GaLPpuuJpzOg/runs/1/artifacts/public/logs/live_backing.log

[task 2020-07-09T17:28:07.737Z] 17:28:07     INFO - TEST-START | devtools/client/framework/test/browser_tab_descriptor_fission.js
[task 2020-07-09T17:28:07.757Z] 17:28:07     INFO - GECKO(2192) | [Parent 2192, Main Thread] WARNING: Failed to update worker context options!: file /builds/worker/checkouts/gecko/dom/workers/WorkerPrivate.cpp, line 1827
[task 2020-07-09T17:28:07.996Z] 17:28:07     INFO - GECKO(2192) | [Child 2656: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fe484007800 == 0 [pid = 2656] [id = {d4948866-468a-4767-949c-b2cb7c2b4053}] [url = http://example.com/browser/devtools/client/framework/test/doc_reload.html]
[task 2020-07-09T17:28:08.057Z] 17:28:08     INFO - GECKO(2192) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpQicrJs.mozrunner/runtests_leaks_tab_pid2685.log
[task 2020-07-09T17:28:08.058Z] 17:28:08     INFO - GECKO(2192) | [2685, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 245
[task 2020-07-09T17:28:08.275Z] 17:28:08     INFO - GECKO(2192) | [Child 2656: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0x7fe4a04936f0) [pid = 2656] [serial = 1] [outer = (nil)] [url = http://example.com/browser/devtools/client/framework/test/doc_reload.html]
[task 2020-07-09T17:28:08.275Z] 17:28:08     INFO - GECKO(2192) | [Child 2656: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (0x7fe48404cc00) [pid = 2656] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-07-09T17:28:08.275Z] 17:28:08     INFO - GECKO(2192) | [Child 2656: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7fe484cf9800) [pid = 2656] [serial = 3] [outer = (nil)] [url = http://example.com/browser/devtools/client/framework/test/doc_empty-tab-01.html]
[task 2020-07-09T17:28:08.275Z] 17:28:08     INFO - GECKO(2192) | [Child 2656: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7fe484cecc00) [pid = 2656] [serial = 4] [outer = (nil)] [url = http://example.com/browser/devtools/client/framework/test/doc_reload.html]
[task 2020-07-09T17:28:08.275Z] 17:28:08     INFO - GECKO(2192) | [Child 2656: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7fe484d03000) [pid = 2656] [serial = 5] [outer = (nil)] [url = http://example.com/browser/devtools/client/framework/test/doc_reload.html]
[task 2020-07-09T17:28:08.295Z] 17:28:08     INFO - GECKO(2192) | [Child 2256: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0x7ff12ef936f0) [pid = 2256] [serial = 41] [outer = (nil)] [url = about:blank]
[task 2020-07-09T17:28:08.302Z] 17:28:08     INFO - GECKO(2192) | [Child 2656, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-07-09T17:28:08.322Z] 17:28:08     INFO - GECKO(2192) | [Child 2656, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp, line 4382
[task 2020-07-09T17:28:08.544Z] 17:28:08     INFO - GECKO(2192) | [Child 2685, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/checkouts/gecko/dom/ipc/ContentChild.cpp, line 1627
[task 2020-07-09T17:28:08.545Z] 17:28:08     INFO - GECKO(2192) | [Child 2685: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f8127de6800 == 1 [pid = 2685] [id = {c1f6de5c-36ac-4595-8599-958b859ae0e5}]
[task 2020-07-09T17:28:08.561Z] 17:28:08     INFO - GECKO(2192) | [Child 2685: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f81435936f0) [pid = 2685] [serial = 1] [outer = (nil)]
[task 2020-07-09T17:28:08.562Z] 17:28:08     INFO - GECKO(2192) | [Child 2685: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f812714c000) [pid = 2685] [serial = 2] [outer = 0x7f81435936f0]
[task 2020-07-09T17:28:08.637Z] 17:28:08     INFO - GECKO(2192) | [Child 2685, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp, line 351
[task 2020-07-09T17:28:08.645Z] 17:28:08     INFO - GECKO(2192) | [Child 2685: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f812d652c00) [pid = 2685] [serial = 3] [outer = 0x7f81435936f0]
[task 2020-07-09T17:28:08.934Z] 17:28:08     INFO - GECKO(2192) | [Parent 2192: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f4da22e6800 == 8 [pid = 2192] [id = {ae339c7e-a22b-40fd-8963-037af23331bc}]
[task 2020-07-09T17:28:08.934Z] 17:28:08     INFO - GECKO(2192) | [Parent 2192: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 18 (0x7f4dbcaf5300) [pid = 2192] [serial = 173] [outer = (nil)]
[task 2020-07-09T17:28:08.934Z] 17:28:08     INFO - GECKO(2192) | [Parent 2192: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 19 (0x7f4da22f0800) [pid = 2192] [serial = 174] [outer = 0x7f4dbcaf5300]
[task 2020-07-09T17:28:08.994Z] 17:28:08     INFO - GECKO(2192) | [Parent 2192: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 20 (0x7f4da32a9400) [pid = 2192] [serial = 175] [outer = 0x7f4dbcaf5300]
[task 2020-07-09T17:28:09.090Z] 17:28:09     INFO - GECKO(2192) | [Parent 2192, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/dom/base/nsContentUtils.cpp, line 3757
[task 2020-07-09T17:28:09.227Z] 17:28:09     INFO - GECKO(2192) | [Parent 2192: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f4da329cc00 == 9 [pid = 2192] [id = {3915dca0-8dca-49b3-971a-f3fd6a9feaf5}]
[task 2020-07-09T17:28:09.227Z] 17:28:09     INFO - GECKO(2192) | [Parent 2192: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 21 (0x7f4dbcaf5870) [pid = 2192] [serial = 176] [outer = (nil)]
[task 2020-07-09T17:28:09.227Z] 17:28:09     INFO - GECKO(2192) | [Parent 2192: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 22 (0x7f4db2183400) [pid = 2192] [serial = 177] [outer = 0x7f4dbcaf5870]
[task 2020-07-09T17:28:09.375Z] 17:28:09     INFO - GECKO(2192) | [Parent 2192, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp, line 1131
[task 2020-07-09T17:28:09.640Z] 17:28:09     INFO - GECKO(2192) | [Parent 2192, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, nullptr) failed with result 0x804B000A (NS_ERROR_MALFORMED_URI): file /builds/worker/checkouts/gecko/extensions/permissions/Permission.cpp, line 46
[task 2020-07-09T17:28:09.640Z] 17:28:09     INFO - GECKO(2192) | [Parent 2192, Main Thread] WARNING: NS_ENSURE_TRUE(principal) failed: file /builds/worker/checkouts/gecko/extensions/permissions/Permission.cpp, line 58
[task 2020-07-09T17:28:09.962Z] 17:28:09     INFO - GECKO(2192) | [Child 2360: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0x7f38f0c19000) [pid = 2360] [serial = 39] [outer = (nil)] [url = about:blank]
[task 2020-07-09T17:28:09.962Z] 17:28:09     INFO - GECKO(2192) | [Child 2360: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f38f0575000 == 0 [pid = 2360] [id = {88d47b57-a648-4160-8263-678a877e5014}] [url = about:blank]
[task 2020-07-09T17:28:10.046Z] 17:28:10     INFO - GECKO(2192) | [Child 2360: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (0x7f390c9936f0) [pid = 2360] [serial = 35] [outer = (nil)] [url = data:text/html,<!doctype html><html>  <head>    <meta charset="utf-8"/>  </head>  <body>    <script src="http://example.com/browser/devtools/client/framework/test/code_bundle_no_race.js"></script>  </body></html>]
[task 2020-07-09T17:28:10.414Z] 17:28:10     INFO - GECKO(2192) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpQicrJs.mozrunner/runtests_leaks_tab_pid2712.log
[task 2020-07-09T17:28:10.415Z] 17:28:10     INFO - GECKO(2192) | [2712, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 245
[task 2020-07-09T17:28:10.510Z] 17:28:10     INFO - GECKO(2192) | [Child 2685, Main Thread] WARNING: nsDocShell::SetDefaultLoadFlags called on Zombie DocShell: file /builds/worker/checkouts/gecko/docshell/base/nsDocShell.cpp, line 4629
[task 2020-07-09T17:28:10.538Z] 17:28:10     INFO - GECKO(2192) | [Child 2685, Main Thread] WARNING: NS_ENSURE_TRUE(request) failed: file /builds/worker/checkouts/gecko/netwerk/base/nsLoadGroup.cpp, line 595
[task 2020-07-09T17:28:10.708Z] 17:28:10     INFO - GECKO(2192) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-07-09T17:28:10.970Z] 17:28:10     INFO - GECKO(2192) | [Child 2712, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/checkouts/gecko/dom/ipc/ContentChild.cpp, line 1627
[task 2020-07-09T17:28:10.991Z] 17:28:10     INFO - GECKO(2192) | [Child 2712: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f807635a800 == 1 [pid = 2712] [id = {c1f6de5c-36ac-4595-8599-958b859ae0e5}]
[task 2020-07-09T17:28:10.991Z] 17:28:10     INFO - GECKO(2192) | [Child 2712, Main Thread] WARNING: NS_ENSURE_TRUE(browserChrome) failed: file /builds/worker/checkouts/gecko/docshell/base/nsDocShell.cpp, line 11387
[task 2020-07-09T17:28:11.026Z] 17:28:11     INFO - GECKO(2192) | [Child 2712: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f808c3936f0) [pid = 2712] [serial = 1] [outer = (nil)]
[task 2020-07-09T17:28:11.030Z] 17:28:11     INFO - GECKO(2192) | [Child 2712: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f80707b1c00) [pid = 2712] [serial = 2] [outer = 0x7f808c3936f0]
[task 2020-07-09T17:28:11.065Z] 17:28:11     INFO - GECKO(2192) | [Child 2685: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f8127de6800 == 0 [pid = 2685] [id = {c1f6de5c-36ac-4595-8599-958b859ae0e5}] [url = http://example.com/document-builder.sjs?html=%3Cdiv%20id=com%3Ecom]
[task 2020-07-09T17:28:11.166Z] 17:28:11     INFO - GECKO(2192) | [Child 2685: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7f81435936f0) [pid = 2685] [serial = 1] [outer = (nil)] [url = http://example.com/document-builder.sjs?html=%3Cdiv%20id=com%3Ecom]
[task 2020-07-09T17:28:11.166Z] 17:28:11     INFO - GECKO(2192) | [Child 2685: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7f812714c000) [pid = 2685] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-07-09T17:28:11.166Z] 17:28:11     INFO - GECKO(2192) | [Child 2685: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7f812d652c00) [pid = 2685] [serial = 3] [outer = (nil)] [url = http://example.com/document-builder.sjs?html=%3Cdiv%20id=com%3Ecom]
[task 2020-07-09T17:28:11.187Z] 17:28:11     INFO - GECKO(2192) | [Child 2685, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-07-09T17:28:11.210Z] 17:28:11     INFO - GECKO(2192) | [Child 2685, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp, line 4382
[task 2020-07-09T17:28:11.293Z] 17:28:11     INFO - GECKO(2192) | [Child 2712, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp, line 351
[task 2020-07-09T17:28:11.308Z] 17:28:11     INFO - GECKO(2192) | [Child 2712: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f80763c8c00) [pid = 2712] [serial = 3] [outer = 0x7f808c3936f0]
[task 2020-07-09T17:28:11.668Z] 17:28:11     INFO - GECKO(2192) | [Parent 2192, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp, line 1605
[task 2020-07-09T17:28:11.684Z] 17:28:11     INFO - GECKO(2192) | console.warn: "fetching stylesheets failed" (new Error("Connection closed, pending request to server0.conn24.child22/styleSheetsActor5, type getStyleSheets failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol/Front.js:256:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:47:19\nmodule.exports@resource://devtools/shared/resources/legacy-listeners/stylesheet.js:18:48\nasync*_watchResourcesForTarget@resource://devtools/shared/resources/resource-watcher.js:387:41\n_startListening@resource://devtools/shared/resources/resource-watcher.js:364:26\nwatchResources@resource://devtools/shared/resources/resource-watcher.js:105:20\nasync*_ensureAllSourcesPopulated/sourcesLoading<@resource://devtools/client/framework/source-map-url-service.js:438:41\n_ensureAllSourcesPopulated@resource://devtools/client/framework/source-map-url-service.js:465:9\nsubscribeByURL@resource://devtools/client/framework/source-map-url-service.js:119:10\nsubscribeByLocation@resource://devtools/client/framework/source-map-url-service.js:163:17\ncomponentWillMount@resource://devtools/client/shared/components/Frame.js:104:73\ncallComponentWillMount@resource://devtools/client/shared/vendor/react-dom.js:8286:14\nmountClassInstance@resource://devtools/client/shared/vendor/react-dom.js:8345:27\nupdateClassComponent@resource://devtools/client/shared/vendor/react-dom.js:10593:23\nbeginWork@resource://devtools/client/shared/vendor/react-dom.js:11419:16\nperformUnitOfWork@resource://devtools/client/shared/vendor/react-dom.js:14702:12\nworkLoop@resource://devtools/client/shared/vendor/react-dom.js:14720:24\nrenderRoot@resource://devtools/client/shared/vendor/react-dom.js:14803:15\nperformWorkOnRoot@resource://devtools/client/shared/vendor/react-dom.js:15655:17\nperformWork@resource://devtools/client/shared/vendor/react-dom.js:15567:24\nperformSyncWork@resource://devtools/client/shared/vendor/react-dom.js:15541:14\nrequestWork@resource://devtools/client/shared/vendor/react-dom.js:15410:5\nscheduleWork@resource://devtools/client/shared/vendor/react-dom.js:15224:16\nenqueueSetState@resource://devtools/client/shared/vendor/react-dom.js:8192:17\nComponent.prototype.setState@resource://devtools/client/shared/vendor/react.js:328:16\nonStateChange@resource://devtools/client/shared/vendor/react-redux.js:1412:16\nnotify@resource://devtools/client/shared/vendor/react-redux.js:1159:21\nnotifyNestedSubs@resource://devtools/client/shared/vendor/react-redux.js:1198:20\nonStateChange@resource://devtools/client/shared/vendor/react-redux.js:1409:16\ndispatch@resource://devtools/client/shared/vendor/redux.js:265:7\neventTelemetryMiddleware/</<@resource://devtools/client/webconsole/middleware/event-telemetry.js:25:21\nhistoryPersistenceMiddleware/</<@resource://devtools/client/webconsole/middleware/history-persistence.js:37:21\nthunkWithOptions/</<@resource://devtools/client/shared/redux/middleware/thunk-with-options.js:17:9\n", "resource://devtools/shared/protocol/Front.js", 77))
[task 2020-07-09T17:28:11.687Z] 17:28:11     INFO - GECKO(2192) | console.warn: "fetching stylesheets failed" (new Error("Connection closed, pending request to server0.conn24.child22/styleSheetsActor5, type getStyleSheets failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol/Front.js:256:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:47:19\nmodule.exports@resource://devtools/shared/resources/legacy-listeners/stylesheet.js:18:48\nasync*_watchResourcesForTarget@resource://devtools/shared/resources/resource-watcher.js:387:41\n_onTargetAvailable@resource://devtools/shared/resources/resource-watcher.js:209:18\nAsync*_emit@resource://devtools/shared/event-emitter.js:226:34\nemitAsync@resource://devtools/shared/event-emitter.js:176:25\nemitAsync@resource://devtools/shared/event-emitter.js:328:25\n_onTargetAvailable@resource://devtools/shared/resources/target-list.js:169:33\nswitchToTarget@resource://devtools/shared/resources/target-list.js:508:16\nonLocalTabRemotenessChange@resource://devtools/shared/resources/target-list.js:491:10\nAsync*_emit@resource://devtools/shared/event-emitter.js:226:34\nemit@resource://devtools/shared/event-emitter.js:172:18\nemit@resource://devtools/shared/event-emitter.js:324:18\n_onRemotenessChange@resource://devtools/client/fronts/targets/local-tab.js:132:12\n_handleTabEvent@resource://devtools/client/fronts/targets/local-tab.js:98:14\ndidChange@chrome://browser/content/tabbrowser.js:5717:15\nfinishChangeRemoteness@chrome://global/content/elements/browser-custom-element.js:1900:12\ngetInterfaceProxy/get/<@chrome://global/content/customElements.js:696:35\n", "resource://devtools/shared/protocol/Front.js", 77))
[task 2020-07-09T17:28:12.092Z] 17:28:12     INFO - GECKO(2192) | [Child 2360: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7f38f0c1e400) [pid = 2360] [serial = 37] [outer = (nil)] [url = data:text/html,<!doctype html><html>  <head>    <meta charset="utf-8"/>  </head>  <body>    <script src="http://example.com/browser/devtools/client/framework/test/code_bundle_no_race.js"></script>  </body></html>]
[task 2020-07-09T17:28:12.173Z] 17:28:12     INFO - GECKO(2192) | [Child 2360: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7f390c993350) [pid = 2360] [serial = 38] [outer = (nil)] [url = about:blank]
[task 2020-07-09T17:28:12.344Z] 17:28:12     INFO - GECKO(2192) | [Child 2256: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (0x7ff112c5d400) [pid = 2256] [serial = 43] [outer = (nil)] [url = about:blank]
[task 2020-07-09T17:28:12.582Z] 17:28:12     INFO - GECKO(2192) | [Child 2461: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7f508c493350) [pid = 2461] [serial = 22] [outer = (nil)] [url = data:text/html,<!doctype html><html>  <head>    <meta charset="utf-8"/>    <title>Empty test page to test race case</title>  </head>  <body>    <script src="http://example.com/browser/devtools/client/framework/test/code_bundle_no_race.js"></script>  </body></html>]
[task 2020-07-09T17:28:13.120Z] 17:28:13     INFO - GECKO(2192) | [Parent 2192: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 21 (0x7f4da22e4000) [pid = 2192] [serial = 169] [outer = (nil)] [url = about:blank]
[task 2020-07-09T17:28:13.121Z] 17:28:13     INFO - GECKO(2192) | [Parent 2192: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 20 (0x7f4db2182400) [pid = 2192] [serial = 167] [outer = (nil)] [url = about:blank]
[task 2020-07-09T17:28:13.122Z] 17:28:13     INFO - GECKO(2192) | [Parent 2192: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 19 (0x7f4da329d000) [pid = 2192] [serial = 165] [outer = (nil)] [url = about:devtools-toolbox]
[task 2020-07-09T17:28:13.181Z] 17:28:13     INFO - GECKO(2192) | [Parent 2192, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp, line 172
[task 2020-07-09T17:28:13.182Z] 17:28:13     INFO - GECKO(2192) | [Parent 2192, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp, line 172
[task 2020-07-09T17:28:13.211Z] 17:28:13     INFO - GECKO(2192) | JavaScript error: resource://devtools/shared/protocol/Front/FrontClassWithSpec.js, line 29: Error: Can not send request 'getWatcher' because front 'tabDescriptor' is already destroyed.
[task 2020-07-09T17:28:13.228Z] 17:28:13     INFO - TEST-INFO | started process screentopng
[task 2020-07-09T17:28:13.830Z] 17:28:13     INFO - TEST-INFO | screentopng: exit 0
[task 2020-07-09T17:28:13.831Z] 17:28:13     INFO - Buffered messages logged at 17:28:07
[task 2020-07-09T17:28:13.832Z] 17:28:13     INFO - Entering test bound 
[task 2020-07-09T17:28:13.833Z] 17:28:13     INFO - Adding a new tab with URL: http://example.com/document-builder.sjs?html=<div id=com>com
[task 2020-07-09T17:28:13.834Z] 17:28:13     INFO - Buffered messages logged at 17:28:08
[task 2020-07-09T17:28:13.834Z] 17:28:13     INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://example.com/document-builder.sjs?html=%3Cdiv%20id=com%3Ecom" line: 0}]
[task 2020-07-09T17:28:13.835Z] 17:28:13     INFO - Tab added and finished loading
[task 2020-07-09T17:28:13.836Z] 17:28:13     INFO - Buffered messages logged at 17:28:10
[task 2020-07-09T17:28:13.840Z] 17:28:13     INFO - Retrieve the initial list of tab descriptors
[task 2020-07-09T17:28:13.841Z] 17:28:13     INFO - TEST-PASS | devtools/client/framework/test/browser_tab_descriptor_fission.js | Should have a descriptor actor for the tab - 
[task 2020-07-09T17:28:13.841Z] 17:28:13     INFO - TEST-PASS | devtools/client/framework/test/browser_tab_descriptor_fission.js | The toolbox target descriptor is the same as the descriptor returned by list tab - 
[task 2020-07-09T17:28:13.842Z] 17:28:13     INFO - Retrieve the target corresponding to the TabDescriptor
[task 2020-07-09T17:28:13.843Z] 17:28:13     INFO - TEST-PASS | devtools/client/framework/test/browser_tab_descriptor_fission.js | The toolbox target is also the target associated with the tab descriptor - 
[task 2020-07-09T17:28:13.843Z] 17:28:13     INFO - Load document "http://example.net/document-builder.sjs?html=<div id=net>net"
[task 2020-07-09T17:28:13.844Z] 17:28:13     INFO - Waiting for page to be loaded…
[task 2020-07-09T17:28:13.845Z] 17:28:13     INFO - Buffered messages logged at 17:28:11
[task 2020-07-09T17:28:13.846Z] 17:28:13     INFO - → page loaded
[task 2020-07-09T17:28:13.846Z] 17:28:13     INFO - Waiting for target switch…
[task 2020-07-09T17:28:13.850Z] 17:28:13     INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://example.net/document-builder.sjs?html=%3Cdiv%20id=net%3Enet" line: 0}]
[task 2020-07-09T17:28:13.851Z] 17:28:13     INFO - → switched-target emitted
[task 2020-07-09T17:28:13.852Z] 17:28:13     INFO - Call list tabs again to update the tab descriptor forms
[task 2020-07-09T17:28:13.853Z] 17:28:13     INFO - TEST-PASS | devtools/client/framework/test/browser_tab_descriptor_fission.js | The existing descriptor now points to the new URI - 
[task 2020-07-09T17:28:13.853Z] 17:28:13     INFO - TEST-PASS | devtools/client/framework/test/browser_tab_descriptor_fission.js | The same tab descriptor instance is reused after navigating - 
[task 2020-07-09T17:28:13.854Z] 17:28:13     INFO - TEST-PASS | devtools/client/framework/test/browser_tab_descriptor_fission.js | With Fission, example.com target front is destroyed - 
[task 2020-07-09T17:28:13.855Z] 17:28:13     INFO - TEST-PASS | devtools/client/framework/test/browser_tab_descriptor_fission.js | With Fission, a new target was created for example.net - 
[task 2020-07-09T17:28:13.855Z] 17:28:13     INFO - Leaving test bound 
[task 2020-07-09T17:28:13.856Z] 17:28:13     INFO - Buffered messages logged at 17:28:13
[task 2020-07-09T17:28:13.857Z] 17:28:13     INFO - Removing tab.
[task 2020-07-09T17:28:13.858Z] 17:28:13     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2020-07-09T17:28:13.859Z] 17:28:13     INFO - Got event: 'TabClose' on [object XULElement].
[task 2020-07-09T17:28:13.860Z] 17:28:13     INFO - Tab removed and finished closing
[task 2020-07-09T17:28:13.862Z] 17:28:13     INFO - Console message: [JavaScript Error: "Error: Can not send request 'getWatcher' because front 'tabDescriptor' is already destroyed." {file: "resource://devtools/shared/protocol/Front/FrontClassWithSpec.js" line: 29}]
[task 2020-07-09T17:28:13.863Z] 17:28:13     INFO - TEST-PASS | devtools/client/framework/test/browser_tab_descriptor_fission.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2020-07-09T17:28:13.864Z] 17:28:13     INFO - Buffered messages finished
[task 2020-07-09T17:28:13.868Z] 17:28:13     INFO - TEST-UNEXPECTED-FAIL | devtools/client/framework/test/browser_tab_descriptor_fission.js | A promise chain failed to handle a rejection: Can not send request 'getWatcher' because front 'tabDescriptor' is already destroyed. - stack: generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:29:15
[task 2020-07-09T17:28:13.869Z] 17:28:13     INFO - startListening@resource://devtools/shared/resources/target-list.js:259:52
[task 2020-07-09T17:28:13.870Z] 17:28:13     INFO - _onTargetAvailable@resource://devtools/shared/resources/target-list.js:177:18
[task 2020-07-09T17:28:13.871Z] 17:28:13     INFO - Async*switchToTarget@resource://devtools/shared/resources/target-list.js:508:16
[task 2020-07-09T17:28:13.872Z] 17:28:13     INFO - onLocalTabRemotenessChange@resource://devtools/shared/resources/target-list.js:491:10
[task 2020-07-09T17:28:13.872Z] 17:28:13     INFO - Async*_emit@resource://devtools/shared/event-emitter.js:226:34
[task 2020-07-09T17:28:13.873Z] 17:28:13     INFO - emit@resource://devtools/shared/event-emitter.js:172:18
[task 2020-07-09T17:28:13.874Z] 17:28:13     INFO - emit@resource://devtools/shared/event-emitter.js:324:18
[task 2020-07-09T17:28:13.875Z] 17:28:13     INFO - _onRemotenessChange@resource://devtools/client/fronts/targets/local-tab.js:132:12
[task 2020-07-09T17:28:13.876Z] 17:28:13     INFO - _handleTabEvent@resource://devtools/client/fronts/targets/local-tab.js:98:14
[task 2020-07-09T17:28:13.876Z] 17:28:13     INFO - didChange@chrome://browser/content/tabbrowser.js:5717:15
[task 2020-07-09T17:28:13.877Z] 17:28:13     INFO - finishChangeRemoteness@chrome://global/content/elements/browser-custom-element.js:1900:12
[task 2020-07-09T17:28:13.878Z] 17:28:13     INFO - getInterfaceProxy/get/<@chrome://global/content/customElements.js:696:35
[task 2020-07-09T17:28:13.879Z] 17:28:13     INFO - Rejection date: Thu Jul 09 2020 17:28:13 GMT+0000 (Coordinated Universal Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 265
[task 2020-07-09T17:28:13.880Z] 17:28:13     INFO - Stack trace:
[task 2020-07-09T17:28:13.881Z] 17:28:13     INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:265
[task 2020-07-09T17:28:13.882Z] 17:28:13     INFO - chrome://mochikit/content/browser-test.js:nextTest:610
[task 2020-07-09T17:28:13.882Z] 17:28:13     INFO - chrome://mochikit/content/browser-test.js:testScope/test_finish/<:1464
[task 2020-07-09T17:28:13.883Z] 17:28:13     INFO - chrome://mochikit/content/browser-test.js:run:1379
[task 2020-07-09T17:28:13.884Z] 17:28:13     INFO - GECKO(2192) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-07-09T17:28:13.885Z] 17:28:13     INFO - GECKO(2192) | MEMORY STAT | vsize 3572MB | residentFast 494MB | heapAllocated 189MB
[task 2020-07-09T17:28:13.886Z] 17:28:13     INFO - TEST-OK | devtools/client/framework/test/browser_tab_descriptor_fission.js | took 5528ms
[task 2020-07-09T17:28:13.887Z] 17:28:13     INFO - GECKO(2192) | [Child 2360: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f38f0572000 == 1 [pid = 2360] [id = {27fe1c01-de68-4a22-8e57-5aed5f3e2f84}]
[task 2020-07-09T17:28:13.888Z] 17:28:13     INFO - GECKO(2192) | [Child 2360: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f390c993350) [pid = 2360] [serial = 41] [outer = (nil)]
[task 2020-07-09T17:28:13.894Z] 17:28:13     INFO - GECKO(2192) | [Child 2360: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f38f0573c00) [pid = 2360] [serial = 42] [outer = 0x7f390c993350]
[task 2020-07-09T17:28:13.895Z] 17:28:13     INFO - GECKO(2192) | [Child 2360: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (0x7f38f0c1a000) [pid = 2360] [serial = 43] [outer = 0x7f390c993350]
[task 2020-07-09T17:28:13.896Z] 17:28:13     INFO - checking window state
[task 2020-07-09T17:28:13.897Z] 17:28:13     INFO - TEST-START | devtools/client/framework/test/browser_target_cached-front.js

I'm on PTO, Julian might have a look at this.

Flags: needinfo?(poirot.alex) → needinfo?(jdescottes)

Will take a look, but the patch landed in Bug 1649910 might help here.

See Also: → https://bugzilla.mozilla.org/show_bug.cgi?id=1649910

No new failure since Bug 1649910 landed, clearing the ni for now.

Flags: needinfo?(jdescottes)

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

Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.