1660466 - Intermittent devtools/shared/resources/tests/browser_target_list* or browser_console_* | <something> [nsIWorkerDebugger.postMessage]

Reporter

Description

•

4 years ago

treeherder

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

[task 2020-08-21T11:34:59.322Z] 11:34:59     INFO - TEST-START | devtools/shared/resources/tests/browser_target_list_getAllTargets.js
[task 2020-08-21T11:34:59.381Z] 11:34:59     INFO - GECKO(2852) | [Child 4020: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0C3BA400 == 4 [pid = 4020] [id = {907df748-11fd-401e-a85a-55f3e5cade4e}]
[task 2020-08-21T11:34:59.381Z] 11:34:59     INFO - GECKO(2852) | [Child 4020: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 16 (0100BDF0) [pid = 4020] [serial = 36] [outer = 00000000]
[task 2020-08-21T11:34:59.381Z] 11:34:59     INFO - GECKO(2852) | [Child 4020: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 17 (0C3BD400) [pid = 4020] [serial = 37] [outer = 0100BDF0]
[task 2020-08-21T11:34:59.516Z] 11:34:59     INFO - GECKO(2852) | [Child 4020: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 18 (0D916C00) [pid = 4020] [serial = 38] [outer = 0100BDF0]
[task 2020-08-21T11:34:59.531Z] 11:34:59     INFO - GECKO(2852) | [Child 4020: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0D917400 == 5 [pid = 4020] [id = {9db07bf1-6e9f-4e62-a68f-fb38ab8ecd0b}]
[task 2020-08-21T11:34:59.531Z] 11:34:59     INFO - GECKO(2852) | [Child 4020: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 19 (0100C230) [pid = 4020] [serial = 39] [outer = 00000000]
[task 2020-08-21T11:34:59.587Z] 11:34:59     INFO - GECKO(2852) | [Child 1700: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 08A28000 == 2 [pid = 1700] [id = {0f515dbc-5192-4e49-a113-f05b3597c574}] [url = https://example.org/browser/devtools/shared/resources/tests/fission_iframe.html]
[task 2020-08-21T11:34:59.587Z] 11:34:59     INFO - GECKO(2852) | [Child 1700: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 068B4400 == 1 [pid = 1700] [id = {fabb9e89-15ae-413c-a370-2e879aab406c}] [url = about:blank]
[task 2020-08-21T11:34:59.587Z] 11:34:59     INFO - GECKO(2852) | [Child 1700: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 068B1C00 == 0 [pid = 1700] [id = {79c14290-8dbe-4ded-9583-6d46972d3add}] [url = https://example.com/browser/devtools/shared/resources/tests//fission_document.html]
[task 2020-08-21T11:34:59.606Z] 11:34:59     INFO - GECKO(2852) | [Child 4020: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 20 (0D91F800) [pid = 4020] [serial = 40] [outer = 0100C230]
[task 2020-08-21T11:34:59.661Z] 11:34:59     INFO - GECKO(2852) | [Parent 1184, 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-08-21T11:34:59.661Z] 11:34:59     INFO - GECKO(2852) | [Parent 1184, Main Thread] WARNING: NS_ENSURE_TRUE(principal) failed: file /builds/worker/checkouts/gecko/extensions/permissions/Permission.cpp, line 58
[task 2020-08-21T11:34:59.661Z] 11:34:59     INFO - GECKO(2852) | [Parent 1184, 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-08-21T11:34:59.661Z] 11:34:59     INFO - GECKO(2852) | [Parent 1184, Main Thread] WARNING: NS_ENSURE_TRUE(principal) failed: file /builds/worker/checkouts/gecko/extensions/permissions/Permission.cpp, line 58
[task 2020-08-21T11:34:59.662Z] 11:34:59     INFO - GECKO(2852) | [Parent 1184, 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-08-21T11:34:59.662Z] 11:34:59     INFO - GECKO(2852) | [Parent 1184, Main Thread] WARNING: NS_ENSURE_TRUE(principal) failed: file /builds/worker/checkouts/gecko/extensions/permissions/Permission.cpp, line 58
[task 2020-08-21T11:34:59.779Z] 11:34:59     INFO - GECKO(2852) | [Child 1700: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (0070B8A0) [pid = 1700] [serial = 33] [outer = 00000000] [url = about:blank]
[task 2020-08-21T11:34:59.779Z] 11:34:59     INFO - GECKO(2852) | [Child 1700: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (0070C560) [pid = 1700] [serial = 26] [outer = 00000000] [url = data:text/html,<div id=div3>]
[task 2020-08-21T11:34:59.780Z] 11:34:59     INFO - GECKO(2852) | [Child 1700: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (0070C670) [pid = 1700] [serial = 31] [outer = 00000000] [url = about:blank]
[task 2020-08-21T11:34:59.780Z] 11:34:59     INFO - GECKO(2852) | [Child 1700: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (08A23C00) [pid = 1700] [serial = 32] [outer = 00000000] [url = about:blank]
[task 2020-08-21T11:34:59.781Z] 11:34:59     INFO - GECKO(2852) | [Child 1700: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (068B8400) [pid = 1700] [serial = 38] [outer = 00000000] [url = about:blank]
[task 2020-08-21T11:34:59.781Z] 11:34:59     INFO - GECKO(2852) | [Child 1700: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (0C965800) [pid = 1700] [serial = 30] [outer = 00000000] [url = data:text/html,<div id=div3>]
[task 2020-08-21T11:34:59.781Z] 11:34:59     INFO - GECKO(2852) | [Child 1700: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (068B2800) [pid = 1700] [serial = 34] [outer = 00000000] [url = about:blank]
[task 2020-08-21T11:34:59.781Z] 11:34:59     INFO - GECKO(2852) | [Child 1700: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (0070C010) [pid = 1700] [serial = 40] [outer = 00000000] [url = https://example.org/browser/devtools/shared/resources/tests/fission_iframe.html]
[task 2020-08-21T11:34:59.782Z] 11:34:59     INFO - GECKO(2852) | [Child 1700: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0070BCE0) [pid = 1700] [serial = 35] [outer = 00000000] [url = about:blank]
[task 2020-08-21T11:34:59.783Z] 11:34:59     INFO - GECKO(2852) | [Child 1700: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (0070BDF0) [pid = 1700] [serial = 37] [outer = 00000000] [url = https://example.com/browser/devtools/shared/resources/tests//fission_document.html]
[task 2020-08-21T11:34:59.783Z] 11:34:59     INFO - GECKO(2852) | [Child 1700: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (068BA400) [pid = 1700] [serial = 36] [outer = 00000000] [url = about:blank]
[task 2020-08-21T11:34:59.783Z] 11:34:59     INFO - GECKO(2852) | [Child 1700: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (08A27800) [pid = 1700] [serial = 39] [outer = 00000000] [url = https://example.com/browser/devtools/shared/resources/tests//fission_document.html]
[task 2020-08-21T11:34:59.783Z] 11:34:59     INFO - GECKO(2852) | [Child 1700: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (08A2D000) [pid = 1700] [serial = 41] [outer = 00000000] [url = https://example.org/browser/devtools/shared/resources/tests/fission_iframe.html]
[task 2020-08-21T11:34:59.802Z] 11:34:59     INFO - GECKO(2852) | [Child 1700, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-08-21T11:35:00.062Z] 11:35:00     INFO - GECKO(2852) | [Child 1700, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp, line 4349
[task 2020-08-21T11:35:00.457Z] 11:35:00     INFO - GECKO(2852) | [Parent 1184, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp, line 578
[task 2020-08-21T11:35:00.660Z] 11:35:00     INFO - GECKO(2852) | [Child 4020: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 19 (0C3BD800) [pid = 4020] [serial = 30] [outer = 00000000] [url = about:blank]
[task 2020-08-21T11:35:00.660Z] 11:35:00     INFO - GECKO(2852) | [Child 4020: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 18 (073B2000) [pid = 4020] [serial = 23] [outer = 00000000] [url = about:blank]
[task 2020-08-21T11:35:00.661Z] 11:35:00     INFO - GECKO(2852) | [Child 4020: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 073BE800 == 4 [pid = 4020] [id = {a8042846-4a25-42dc-80de-ea7b80674700}] [url = https://example.com/browser/devtools/shared/resources/tests/fission_document.html]
[task 2020-08-21T11:35:00.661Z] 11:35:00     INFO - GECKO(2852) | [Child 4020: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0D919C00 == 3 [pid = 4020] [id = {9dc9b33c-d20f-469a-a76e-1d8fabb80b27}] [url = https://example.org/browser/devtools/shared/resources/tests/fission_iframe.html]
[task 2020-08-21T11:35:00.661Z] 11:35:00     INFO - GECKO(2852) | [Child 4020: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 073B4C00 == 2 [pid = 4020] [id = {cd3dba0c-4a03-4bac-a95d-c04ac5e5aab3}] [url = about:blank]
[task 2020-08-21T11:35:00.760Z] 11:35:00     INFO - GECKO(2852) | [Child 4020: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 17 (0100C340) [pid = 4020] [serial = 32] [outer = 00000000] [url = https://example.org/browser/devtools/shared/resources/tests/fission_iframe.html]
[task 2020-08-21T11:35:00.760Z] 11:35:00     INFO - GECKO(2852) | [Child 4020: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 16 (0100C010) [pid = 4020] [serial = 27] [outer = 00000000] [url = about:blank]
[task 2020-08-21T11:35:00.761Z] 11:35:00     INFO - GECKO(2852) | [Child 4020: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 15 (0100BF00) [pid = 4020] [serial = 25] [outer = 00000000] [url = about:blank]
[task 2020-08-21T11:35:00.761Z] 11:35:00     INFO - GECKO(2852) | [Child 4020: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (0C3BF400) [pid = 4020] [serial = 19] [outer = 00000000] [url = about:blank]
[task 2020-08-21T11:35:00.761Z] 11:35:00     INFO - GECKO(2852) | [Child 4020: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (0C3BAC00) [pid = 4020] [serial = 24] [outer = 00000000] [url = data:text/html,Root Node tests]
[task 2020-08-21T11:35:00.761Z] 11:35:00     INFO - GECKO(2852) | [Child 4020: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (073B3800) [pid = 4020] [serial = 21] [outer = 00000000] [url = about:blank]
[task 2020-08-21T11:35:00.824Z] 11:35:00     INFO - GECKO(2852) | [Parent 1184: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (0FE53800) [pid = 1184] [serial = 15] [outer = 00000000] [url = about:blank]
[task 2020-08-21T11:35:00.891Z] 11:35:00     INFO - GECKO(2852) | [Child 5756: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (0B7B3000) [pid = 5756] [serial = 45] [outer = 00000000] [url = about:blank]
[task 2020-08-21T11:35:00.891Z] 11:35:00     INFO - GECKO(2852) | [Child 5756: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (0B7AF400) [pid = 5756] [serial = 42] [outer = 00000000] [url = about:blank]
[task 2020-08-21T11:35:00.891Z] 11:35:00     INFO - GECKO(2852) | [Child 5756: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (06CD0800) [pid = 5756] [serial = 37] [outer = 00000000] [url = about:blank]
[task 2020-08-21T11:35:01.006Z] 11:35:01     INFO - GECKO(2852) | [Child 5756: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (00A0B9B0) [pid = 5756] [serial = 41] [outer = 00000000] [url = data:text/html,Test]
[task 2020-08-21T11:35:01.006Z] 11:35:01     INFO - GECKO(2852) | [Child 5756: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (00A0C120) [pid = 5756] [serial = 44] [outer = 00000000] [url = http://example.com/browser/devtools/shared/resources/tests/websocket_frontend.html]
[task 2020-08-21T11:35:01.006Z] 11:35:01     INFO - GECKO(2852) | [Child 5756: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (0B7AA400) [pid = 5756] [serial = 35] [outer = 00000000] [url = http://example.com/document-builder.sjs?html=com]
[task 2020-08-21T11:35:01.006Z] 11:35:01     INFO - GECKO(2852) | [Child 5756: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (00A0BF00) [pid = 5756] [serial = 36] [outer = 00000000] [url = https://example.com/browser/devtools/shared/resources/tests/style_document.html]
[task 2020-08-21T11:35:01.007Z] 11:35:01     INFO - GECKO(2852) | [Child 5756: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (0B7AE000) [pid = 5756] [serial = 40] [outer = 00000000] [url = https://example.org/browser/devtools/shared/resources/tests/style_iframe.html]
[task 2020-08-21T11:35:01.230Z] 11:35:01     INFO - GECKO(2852) | [Parent 1184, Main Thread] WARNING: A debugger runnable was posted to a worker that is already shutting down!: file /builds/worker/checkouts/gecko/dom/workers/WorkerPrivate.cpp, line 1540
[task 2020-08-21T11:35:01.236Z] 11:35:01     INFO - GECKO(2852) | [Parent 1184, Main Thread] WARNING: A debugger runnable was posted to a worker that is already shutting down!: file /builds/worker/checkouts/gecko/dom/workers/WorkerPrivate.cpp, line 1540
[task 2020-08-21T11:35:01.236Z] 11:35:01     INFO - TEST-INFO | started process screenshot
[task 2020-08-21T11:35:01.304Z] 11:35:01     INFO - TEST-INFO | screenshot: exit 0
[task 2020-08-21T11:35:01.305Z] 11:35:01     INFO - Buffered messages logged at 11:34:59
[task 2020-08-21T11:35:01.305Z] 11:35:01     INFO - Entering test bound 
[task 2020-08-21T11:35:01.305Z] 11:35:01     INFO - Setup the test page with workers of all types
[task 2020-08-21T11:35:01.306Z] 11:35:01     INFO - Adding a new tab with URL: https://example.com/browser/devtools/shared/resources/tests/fission_document.html
[task 2020-08-21T11:35:01.306Z] 11:35:01     INFO - Tab added and finished loading
[task 2020-08-21T11:35:01.306Z] 11:35:01     INFO - Create a target list for the main process target
[task 2020-08-21T11:35:01.307Z] 11:35:01     INFO - Buffered messages finished
[task 2020-08-21T11:35:01.307Z] 11:35:01     INFO - TEST-UNEXPECTED-FAIL | devtools/shared/resources/tests/browser_target_list_getAllTargets.js | Uncaught exception - at resource://devtools/shared/protocol/Front.js:327 - Error: [Exception... "Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIWorkerDebugger.postMessage]"  nsresult: "0x80004005 (NS_ERROR_FAILURE)"  location: "JS frame :: resource://devtools/server/connectors/worker-connector.js :: connectToWorker/< :: line 85"  data: no] from: server0.conn28.workerTarget45
[task 2020-08-21T11:35:01.307Z] 11:35:01     INFO - Stack trace:
[task 2020-08-21T11:35:01.307Z] 11:35:01     INFO - onPacket/<@resource://devtools/shared/protocol/Front.js:327:31
[task 2020-08-21T11:35:01.308Z] 11:35:01     INFO - DevTools RDP*request@resource://devtools/shared/protocol/Front.js:260:14
[task 2020-08-21T11:35:01.308Z] 11:35:01     INFO - generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:46:19
[task 2020-08-21T11:35:01.308Z] 11:35:01     INFO - attach/this._attach<@resource://devtools/client/fronts/targets/worker.js:89:42
[task 2020-08-21T11:35:01.308Z] 11:35:01     INFO - async*attach@resource://devtools/client/fronts/targets/worker.js:100:7
[task 2020-08-21T11:35:01.308Z] 11:35:01     INFO - _attachAndInitThread@resource://devtools/client/fronts/targets/target-mixin.js:519:18
[task 2020-08-21T11:35:01.308Z] 11:35:01     INFO - attachAndInitThread@resource://devtools/client/fronts/targets/target-mixin.js:502:40
[task 2020-08-21T11:35:01.308Z] 11:35:01     INFO - _onTargetAvailable@resource://devtools/shared/resources/target-list.js:182:23
[task 2020-08-21T11:35:01.308Z] 11:35:01     INFO - _processNewWorkerTarget@resource://devtools/shared/resources/legacy-target-watchers/legacy-workers-watcher.js:128:18
[task 2020-08-21T11:35:01.309Z] 11:35:01     INFO - _workerListChanged/promises<@resource://devtools/shared/resources/legacy-target-watchers/legacy-workers-watcher.js:105:12
[task 2020-08-21T11:35:01.309Z] 11:35:01     INFO - _workerListChanged@resource://devtools/shared/resources/legacy-target-watchers/legacy-workers-watcher.js:104:30
[task 2020-08-21T11:35:01.309Z] 11:35:01     INFO - async*_onProcessAvailable@resource://devtools/shared/resources/legacy-target-watchers/legacy-workers-watcher.js:45:16
[task 2020-08-21T11:35:01.309Z] 11:35:01     INFO - listen@resource://devtools/shared/resources/legacy-target-watchers/legacy-workers-watcher.js:145:18
[task 2020-08-21T11:35:01.309Z] 11:35:01     INFO - async*startListening@resource://devtools/shared/resources/target-list.js:299:47
[task 2020-08-21T11:35:01.310Z] 11:35:01     INFO - async*@chrome://mochitests/content/browser/devtools/shared/resources/tests/browser_target_list_getAllTargets.js:38:20
[task 2020-08-21T11:35:01.310Z] 11:35:01     INFO - Async*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1069:34
[task 2020-08-21T11:35:01.310Z] 11:35:01     INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1109:11
[task 2020-08-21T11:35:01.310Z] 11:35:01     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:932:14
[task 2020-08-21T11:35:01.310Z] 11:35:01     INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1037:23
[task 2020-08-21T11:35:01.311Z] 11:35:01     INFO - Leaving test bound 
[task 2020-08-21T11:35:01.311Z] 11:35:01     INFO - GECKO(2852) | [Parent 1184, Main Thread] WARNING: Failed to update worker context options!: file /builds/worker/checkouts/gecko/dom/workers/WorkerPrivate.cpp, line 1830
[task 2020-08-21T11:35:01.311Z] 11:35:01     INFO - GECKO(2852) | [Parent 1184, Main Thread] WARNING: Failed to update worker context options!: file /builds/worker/checkouts/gecko/dom/workers/WorkerPrivate.cpp, line 1830
[task 2020-08-21T11:35:01.312Z] 11:35:01     INFO - Removing tab.
[task 2020-08-21T11:35:01.312Z] 11:35:01     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2020-08-21T11:35:01.312Z] 11:35:01     INFO - Got event: 'TabClose' on [object XULElement].
[task 2020-08-21T11:35:01.312Z] 11:35:01     INFO - Tab removed and finished closing
[task 2020-08-21T11:35:01.344Z] 11:35:01     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-21T11:35:01.344Z] 11:35:01     INFO - TEST-UNEXPECTED-FAIL | devtools/shared/resources/tests/browser_target_list_getAllTargets.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2020-08-21T11:35:01.344Z] 11:35:01     INFO - Stack trace:
[task 2020-08-21T11:35:01.345Z] 11:35:01     INFO - chrome://mochikit/content/browser-test.js:test_ok:1304
[task 2020-08-21T11:35:01.345Z] 11:35:01     INFO - chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:cleanup:307
[task 2020-08-21T11:35:01.389Z] 11:35:01     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-21T11:35:01.396Z] 11:35:01     INFO - TEST-UNEXPECTED-FAIL | devtools/shared/resources/tests/browser_target_list_getAllTargets.js | A promise chain failed to handle a rejection: [Exception... "Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIWorkerDebugger.postMessage]"  nsresult: "0x80004005 (NS_ERROR_FAILURE)"  location: "JS frame :: resource://devtools/server/connectors/worker-connector.js :: connectToWorker/< :: line 85"  data: no] from: server0.conn28.workerTarget47 - stack: onPacket/<@resource://devtools/shared/protocol/Front.js:327:31
[task 2020-08-21T11:35:01.396Z] 11:35:01     INFO - DevTools RDP*request@resource://devtools/shared/protocol/Front.js:260:14
[task 2020-08-21T11:35:01.396Z] 11:35:01     INFO - generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:46:19
[task 2020-08-21T11:35:01.396Z] 11:35:01     INFO - attach/this._attach<@resource://devtools/client/fronts/targets/worker.js:89:42
[task 2020-08-21T11:35:01.396Z] 11:35:01     INFO - async*attach@resource://devtools/client/fronts/targets/worker.js:100:7
[task 2020-08-21T11:35:01.396Z] 11:35:01     INFO - _attachAndInitThread@resource://devtools/client/fronts/targets/target-mixin.js:519:18
[task 2020-08-21T11:35:01.396Z] 11:35:01     INFO - attachAndInitThread@resource://devtools/client/fronts/targets/target-mixin.js:502:40
[task 2020-08-21T11:35:01.397Z] 11:35:01     INFO - _onTargetAvailable@resource://devtools/shared/resources/target-list.js:182:23
[task 2020-08-21T11:35:01.397Z] 11:35:01     INFO - _processNewWorkerTarget@resource://devtools/shared/resources/legacy-target-watchers/legacy-workers-watcher.js:128:18
[task 2020-08-21T11:35:01.397Z] 11:35:01     INFO - _workerListChanged/promises<@resource://devtools/shared/resources/legacy-target-watchers/legacy-workers-watcher.js:105:12
[task 2020-08-21T11:35:01.397Z] 11:35:01     INFO - _workerListChanged@resource://devtools/shared/resources/legacy-target-watchers/legacy-workers-watcher.js:104:30
[task 2020-08-21T11:35:01.397Z] 11:35:01     INFO - async*_onProcessAvailable@resource://devtools/shared/resources/legacy-target-watchers/legacy-workers-watcher.js:45:16
[task 2020-08-21T11:35:01.397Z] 11:35:01     INFO - listen@resource://devtools/shared/resources/legacy-target-watchers/legacy-workers-watcher.js:145:18
[task 2020-08-21T11:35:01.397Z] 11:35:01     INFO - async*startListening@resource://devtools/shared/resources/target-list.js:299:47
[task 2020-08-21T11:35:01.397Z] 11:35:01     INFO - async*@chrome://mochitests/content/browser/devtools/shared/resources/tests/browser_target_list_getAllTargets.js:38:20
[task 2020-08-21T11:35:01.397Z] 11:35:01     INFO - Async*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1069:34
[task 2020-08-21T11:35:01.397Z] 11:35:01     INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1109:11
[task 2020-08-21T11:35:01.397Z] 11:35:01     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:932:14
[task 2020-08-21T11:35:01.397Z] 11:35:01     INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1037:23
[task 2020-08-21T11:35:01.397Z] 11:35:01     INFO - Rejection date: Fri Aug 21 2020 11:35:01 GMT+0000 (Greenwich Mean Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 265
[task 2020-08-21T11:35:01.398Z] 11:35:01     INFO - Stack trace:
[task 2020-08-21T11:35:01.398Z] 11:35:01     INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:265
[task 2020-08-21T11:35:01.398Z] 11:35:01     INFO - chrome://mochikit/content/browser-test.js:nextTest:615
[task 2020-08-21T11:35:01.398Z] 11:35:01     INFO - chrome://mochikit/content/browser-test.js:testScope/test_finish/<:1512
[task 2020-08-21T11:35:01.398Z] 11:35:01     INFO - chrome://mochikit/content/browser-test.js:run:1427
[task 2020-08-21T11:35:01.398Z] 11:35:01     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-21T11:35:01.399Z] 11:35:01     INFO - TEST-UNEXPECTED-FAIL | devtools/shared/resources/tests/browser_target_list_getAllTargets.js | A promise chain failed to handle a rejection: [Exception... "Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIWorkerDebugger.postMessage]"  nsresult: "0x80004005 (NS_ERROR_FAILURE)"  location: "JS frame :: resource://devtools/server/connectors/worker-connector.js :: connectToWorker/< :: line 85"  data: no] from: server0.conn28.workerTarget45 - stack: onPacket/<@resource://devtools/shared/protocol/Front.js:327:31
[task 2020-08-21T11:35:01.399Z] 11:35:01     INFO - DevTools RDP*request@resource://devtools/shared/protocol/Front.js:260:14
[task 2020-08-21T11:35:01.399Z] 11:35:01     INFO - generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:46:19
[task 2020-08-21T11:35:01.399Z] 11:35:01     INFO - attach/this._attach<@resource://devtools/client/fronts/targets/worker.js:89:42
[task 2020-08-21T11:35:01.399Z] 11:35:01     INFO - async*attach@resource://devtools/client/fronts/targets/worker.js:100:7
[task 2020-08-21T11:35:01.399Z] 11:35:01     INFO - _attachAndInitThread@resource://devtools/client/fronts/targets/target-mixin.js:519:18
[task 2020-08-21T11:35:01.399Z] 11:35:01     INFO - attachAndInitThread@resource://devtools/client/fronts/targets/target-mixin.js:502:40
[task 2020-08-21T11:35:01.399Z] 11:35:01     INFO - _onTargetAvailable@resource://devtools/shared/resources/target-list.js:182:23
[task 2020-08-21T11:35:01.399Z] 11:35:01     INFO - _processNewWorkerTarget@resource://devtools/shared/resources/legacy-target-watchers/legacy-workers-watcher.js:128:18
[task 2020-08-21T11:35:01.399Z] 11:35:01     INFO - _workerListChanged/promises<@resource://devtools/shared/resources/legacy-target-watchers/legacy-workers-watcher.js:105:12
[task 2020-08-21T11:35:01.400Z] 11:35:01     INFO - _workerListChanged@resource://devtools/shared/resources/legacy-target-watchers/legacy-workers-watcher.js:104:30
[task 2020-08-21T11:35:01.400Z] 11:35:01     INFO - async*_onProcessAvailable@resource://devtools/shared/resources/legacy-target-watchers/legacy-workers-watcher.js:45:16
[task 2020-08-21T11:35:01.400Z] 11:35:01     INFO - listen@resource://devtools/shared/resources/legacy-target-watchers/legacy-workers-watcher.js:145:18
[task 2020-08-21T11:35:01.400Z] 11:35:01     INFO - async*startListening@resource://devtools/shared/resources/target-list.js:299:47
[task 2020-08-21T11:35:01.400Z] 11:35:01     INFO - async*@chrome://mochitests/content/browser/devtools/shared/resources/tests/browser_target_list_getAllTargets.js:38:20
[task 2020-08-21T11:35:01.400Z] 11:35:01     INFO - Async*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1069:34
[task 2020-08-21T11:35:01.400Z] 11:35:01     INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1109:11
[task 2020-08-21T11:35:01.400Z] 11:35:01     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:932:14
[task 2020-08-21T11:35:01.401Z] 11:35:01     INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1037:23
[task 2020-08-21T11:35:01.401Z] 11:35:01     INFO - Rejection date: Fri Aug 21 2020 11:35:01 GMT+0000 (Greenwich Mean Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 265
[task 2020-08-21T11:35:01.401Z] 11:35:01     INFO - Stack trace:
[task 2020-08-21T11:35:01.401Z] 11:35:01     INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:265
[task 2020-08-21T11:35:01.401Z] 11:35:01     INFO - chrome://mochikit/content/browser-test.js:nextTest:615
[task 2020-08-21T11:35:01.401Z] 11:35:01     INFO - chrome://mochikit/content/browser-test.js:testScope/test_finish/<:1512
[task 2020-08-21T11:35:01.401Z] 11:35:01     INFO - chrome://mochikit/content/browser-test.js:run:1427
[task 2020-08-21T11:35:01.401Z] 11:35:01     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-21T11:35:01.401Z] 11:35:01     INFO - TEST-UNEXPECTED-FAIL | devtools/shared/resources/tests/browser_target_list_getAllTargets.js | A promise chain failed to handle a rejection: Connection closed, pending request to root, type listWorkers failed
[task 2020-08-21T11:35:01.401Z] 11:35:01     INFO - 
[task 2020-08-21T11:35:01.401Z] 11:35:01     INFO - Request stack:
[task 2020-08-21T11:35:01.401Z] 11:35:01     INFO - request@resource://devtools/shared/protocol/Front.js:260:14
[task 2020-08-21T11:35:01.401Z] 11:35:01     INFO - generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:46:19
[task 2020-08-21T11:35:01.402Z] 11:35:01     INFO - _workerListChanged@resource://devtools/shared/resources/legacy-target-watchers/legacy-workers-watcher.js:84:37
[task 2020-08-21T11:35:01.402Z] 11:35:01     INFO - _emit@resource://devtools/shared/event-emitter.js:226:34
[task 2020-08-21T11:35:01.402Z] 11:35:01     INFO - emit@resource://devtools/shared/event-emitter.js:172:18
[task 2020-08-21T11:35:01.402Z] 11:35:01     INFO - emit@resource://devtools/shared/event-emitter.js:324:18
[task 2020-08-21T11:35:01.402Z] 11:35:01     INFO - onPacket@resource://devtools/shared/protocol/Front.js:298:13
[task 2020-08-21T11:35:01.402Z] 11:35:01     INFO - onPacket@resource://devtools/client/devtools-client.js:492:13
[task 2020-08-21T11:35:01.402Z] 11:35:01     INFO - send/<@resource://devtools/shared/transport/local-transport.js:68:25
[task 2020-08-21T11:35:01.402Z] 11:35:01     INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:103:22
[task 2020-08-21T11:35:01.402Z] 11:35:01     INFO - DevToolsUtils.executeSoon*exports.executeSoon@resource://devtools/shared/DevToolsUtils.js:47:21
[task 2020-08-21T11:35:01.402Z] 11:35:01     INFO - send@resource://devtools/shared/transport/local-transport.js:56:21
[task 2020-08-21T11:35:01.402Z] 11:35:01     INFO - send@resource://devtools/server/devtools-server-connection.js:99:20
[task 2020-08-21T11:35:01.402Z] 11:35:01     INFO - onWorkerListChanged@resource://devtools/server/actors/root.js:442:15
[task 2020-08-21T11:35:01.402Z] 11:35:01     INFO - _notifyListChanged@resource://devtools/server/actors/worker/worker-target-actor-list.js:184:10
[task 2020-08-21T11:35:01.402Z] 11:35:01     INFO - onUnregister@resource://devtools/server/actors/worker/worker-target-actor-list.js:200:12
[task 2020-08-21T11:35:01.402Z] 11:35:01     INFO -  - stack: destroy@resource://devtools/shared/protocol/Front.js:77:23
[task 2020-08-21T11:35:01.402Z] 11:35:01     INFO - onClosed@resource://devtools/client/devtools-client.js:628:12
[task 2020-08-21T11:35:01.402Z] 11:35:01     INFO - close@resource://devtools/shared/transport/local-transport.js:169:20
[task 2020-08-21T11:35:01.402Z] 11:35:01     INFO - close@resource://devtools/shared/transport/local-transport.js:165:13
[task 2020-08-21T11:35:01.403Z] 11:35:01     INFO - close@resource://devtools/server/devtools-server-connection.js:94:23
[task 2020-08-21T11:35:01.403Z] 11:35:01     INFO - cleanup@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:315:12
[task 2020-08-21T11:35:01.403Z] 11:35:01     INFO - Rejection date: Fri Aug 21 2020 11:35:01 GMT+0000 (Greenwich Mean Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 265
[task 2020-08-21T11:35:01.403Z] 11:35:01     INFO - Stack trace:
[task 2020-08-21T11:35:01.403Z] 11:35:01     INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:265
[task 2020-08-21T11:35:01.403Z] 11:35:01     INFO - chrome://mochikit/content/browser-test.js:nextTest:615
[task 2020-08-21T11:35:01.403Z] 11:35:01     INFO - chrome://mochikit/content/browser-test.js:testScope/test_finish/<:1512
[task 2020-08-21T11:35:01.403Z] 11:35:01     INFO - chrome://mochikit/content/browser-test.js:run:1427
[task 2020-08-21T11:35:01.403Z] 11:35:01     INFO - GECKO(2852) | [Child 4020, Main Thread] WARNING: A debugger runnable was posted to a worker that is already shutting down!: file /builds/worker/checkouts/gecko/dom/workers/WorkerPrivate.cpp, line 1540
[task 2020-08-21T11:35:01.406Z] 11:35:01     INFO - GECKO(2852) | [Child 4020, Main Thread] WARNING: A debugger runnable was posted to a worker that is already shutting down!: file /builds/worker/checkouts/gecko/dom/workers/WorkerPrivate.cpp, line 1540
[task 2020-08-21T11:35:01.406Z] 11:35:01     INFO - GECKO(2852) | JavaScript error: resource://devtools/shared/protocol/Front.js, line 327: Error: [Exception... "Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIWorkerDebugger.postMessage]"  nsresult: "0x80004005 (NS_ERROR_FAILURE)"  location: "JS frame :: resource://devtools/server/connectors/worker-connector.js :: connectToWorker/< :: line 85"  data: no] from: server0.conn28.workerTarget47
[task 2020-08-21T11:35:01.409Z] 11:35:01     INFO - GECKO(2852) | JavaScript error: resource://devtools/shared/protocol/Front.js, line 327: Error: [Exception... "Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIWorkerDebugger.postMessage]"  nsresult: "0x80004005 (NS_ERROR_FAILURE)"  location: "JS frame :: resource://devtools/server/connectors/worker-connector.js :: connectToWorker/< :: line 85"  data: no] from: server0.conn28.workerTarget45
[task 2020-08-21T11:35:01.409Z] 11:35:01     INFO - GECKO(2852) | JavaScript error: resource://devtools/shared/protocol/Front.js, line 77: Error: Connection closed, pending request to root, type listWorkers failed

Cristina Coroiu [:ccoroiu]

Comment 1

•

4 years ago

Nicolas, could you push https://hg.mozilla.org/integration/autoland/rev/2839eaa25b4e0d4185b938df1ecff76ee85d3f1b be the culprit?
Can you please take a look? Thank you

Flags: needinfo?(nchevobbe)

Regressed by: 1657310

Whiteboard: [retriggered]

BMO Automation

Updated

•

4 years ago

Has Regression Range: --- → yes

Keywords: regression

Comment hidden (Intermittent Failures Robot)

Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)

Updated

•

4 years ago

Summary: Intermittent devtools/shared/resources/tests/browser_target_list_getAllTargets.js | Uncaught exception - at protocol/Front.js:327 - Error: [Exception... "Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIWorkerDebugger.postMessage]" nsre → Intermittent devtools/shared/resources/tests/browser_target_list* or browser_console_* | <something> [nsIWorkerDebugger.postMessage]

Comment hidden (Intermittent Failures Robot)

BugBot [:suhaib / :marco/ :calixte]

Comment 6

•

4 years ago

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

status-firefox80: --- → unaffected

status-firefox81: --- → affected

status-firefox82: --- → affected

status-firefox-esr68: --- → unaffected

status-firefox-esr78: --- → unaffected

Nicolas Chevobbe [:nchevobbe]

Assignee

Comment 7

•

4 years ago

I'll have a look

Flags: needinfo?(nchevobbe)

Comment hidden (Intermittent Failures Robot)

Nicolas Chevobbe [:nchevobbe]

Assignee

Updated

•

4 years ago

Assignee: nobody → nchevobbe

Status: NEW → ASSIGNED

Nicolas Chevobbe [:nchevobbe]

Assignee

Updated

•

4 years ago

Depends on: 1662054

Nicolas Chevobbe [:nchevobbe]

Assignee

Comment 13

•

4 years ago

Attached file Bug 1660466 - Fix attaching target/thread test intermittents. r=jdescottes. (deleted) — Details

Move the function taking care of closing the Browser Console to shared-head so
we can close it before closing the tabs opened during the test.

While attaching the worker, check that the Worker Debugger isn't closed, and
if it is, reject the promise. On the target list, catch rejection while attaching
and simply bail out so we don't call the rest of the code in onTargetAvailable.

Depends on D88765

Comment hidden (Intermittent Failures Robot)

Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)

Comment 19

•

4 years ago

Julian, can you review Nicolas' fix for this very frequent failure, please?

Flags: needinfo?(jdescottes)

Julian Descottes [:jdescottes]

Comment 20

•

4 years ago

(In reply to Sebastian Hengst [:aryx] (needinfo on intermittent or backout) from comment #19)

Julian, can you review Nicolas' fix for this very frequent failure, please?

I am waiting for the patch to be updated because the current version still has intermittents.
This is in progress.

Flags: needinfo?(jdescottes)

Pulsebot

Comment 21

•

4 years ago

Pushed by nchevobbe@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/9cca0967f023 Fix attaching target/thread test intermittents. r=jdescottes.

Noemi Erli[:noemi_erli]

Comment 22

•

4 years ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/9cca0967f023

Status: ASSIGNED → RESOLVED

Closed: 4 years ago

status-firefox82: affected → fixed

Resolution: --- → FIXED

Target Milestone: --- → 82 Branch

Comment hidden (Intermittent Failures Robot)

BugBot [:suhaib / :marco/ :calixte]

Comment 25

•

4 years ago

The patch landed in nightly and beta is affected.
:nchevobbe, is this bug important enough to require an uplift?
If not please set status_beta to wontfix.

For more information, please visit auto_nag documentation.

Flags: needinfo?(nchevobbe)

Julian Descottes [:jdescottes]

Comment 26

•

4 years ago

Nicolas is on PTO and the patch mostly impacts intermittent failures on try, forwarding the ni? to Aryx to decide if this is worth uplifting.
On the technical it should be safe to uplift, as long as we also uplift Bug 1662054 as dependency.

Flags: needinfo?(nchevobbe) → needinfo?(aryx.bugmail)

Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)

Updated

•

4 years ago

Flags: needinfo?(aryx.bugmail) → needinfo?(ryanvm)

Ryan VanderMeulen [:RyanVM]

Comment 27

•

4 years ago

Please nominate both bugs.

Flags: needinfo?(ryanvm) → needinfo?(jdescottes)

Julian Descottes [:jdescottes]

Comment 28

•

4 years ago

Comment on attachment 9172971 [details]
Bug 1660466 - Fix attaching target/thread test intermittents. r=jdescottes.

Beta/Release Uplift Approval Request

User impact if declined: Many intermittent failures on try
Is this code covered by automated tests?: Yes
Has the fix been verified in Nightly?: Yes
Needs manual test from QE?: No
If yes, steps to reproduce:
List of other uplifts needed: Bug 1662054
Risk to taking this patch: Low
Why is the change risky/not risky? (and alternatives if risky): Mostly test change
String changes made/needed:

Flags: needinfo?(jdescottes)

Attachment #9172971 - Flags: approval-mozilla-beta?

Ryan VanderMeulen [:RyanVM]

Comment 29

•

4 years ago

Comment on attachment 9172971 [details]
Bug 1660466 - Fix attaching target/thread test intermittents. r=jdescottes.

Approved for 81.0b8.

Attachment #9172971 - Flags: approval-mozilla-beta? → approval-mozilla-beta+

Ryan VanderMeulen [:RyanVM]

Comment 30

•

4 years ago

bugherder uplift

https://hg.mozilla.org/releases/mozilla-beta/rev/de8d286d5253

status-firefox81: affected → fixed