1737194 - Intermittent .../.../commands/target/tests/browser_target_command_various_descriptors.js | Uncaught exception - at resource: .../Front.js:106 - Error: Connection closed, pending request to server0.../contentProcessTarget1, type listWorkers failed

Status: RESOLVED FIXED

(DevTools :: Framework, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2021-10-22T00:46:52.507Z] 00:46:52     INFO - TEST-START | devtools/shared/commands/target/tests/browser_target_command_various_descriptors.js
[task 2021-10-22T00:46:52.856Z] 00:46:52     INFO - GECKO(10164) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-10-22T00:46:53.125Z] 00:46:53     INFO - GECKO(10164) | console.error: "Tried to send a 'target-destroyed-form' event on an already destroyed actor 'watcher'"
[task 2021-10-22T00:46:53.161Z] 00:46:53     INFO - GECKO(10164) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-10-22T00:46:53.698Z] 00:46:53     INFO - GECKO(10164) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-10-22T00:46:54.125Z] 00:46:54     INFO - GECKO(10164) | console.error: "Error while calling actor 'console's method 'startListeners'" "this.targetActor.attach is not a function"
[task 2021-10-22T00:46:54.140Z] 00:46:54     INFO - GECKO(10164) | JavaScript warning: resource://devtools/shared/builtin-modules.js, line 202: debuggee 'resource://devtools/shared/base-loader.js:289' would run
[task 2021-10-22T00:46:54.146Z] 00:46:54     INFO - GECKO(10164) | console.error: (new TypeError("this.targetActor.attach is not a function", "resource://devtools/server/actors/webconsole/listeners/document-events.js", 70))
[task 2021-10-22T00:46:54.148Z] 00:46:54     INFO - GECKO(10164) | console.error: "Failed to start [document-event] legacy listener for target server0.conn23.content-process3/contentProcessTarget1" (new Error("Protocol error (TypeError): this.targetActor.attach is not a function from: server0.conn23.content-process3/console2 (resource://devtools/server/actors/webconsole/listeners/document-events.js:70:24)", "resource://devtools/shared/protocol/Front.js", 365))
[task 2021-10-22T00:46:54.343Z] 00:46:54     INFO - GECKO(10164) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-10-22T00:46:54.355Z] 00:46:54     INFO - TEST-INFO | started process screenshot
[task 2021-10-22T00:46:54.464Z] 00:46:54     INFO - TEST-INFO | screenshot: exit 0
...[task 2021-10-22T00:46:54.494Z] 00:46:54     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_command_various_descriptors.js | Found the worker Debugger ID - 
[task 2021-10-22T00:46:54.495Z] 00:46:54     INFO - Console message: [JavaScript Warning: "debuggee 'resource://devtools/shared/base-loader.js:289' would run" {file: "resource://devtools/shared/builtin-modules.js" line: 202}]
[task 2021-10-22T00:46:54.496Z] 00:46:54     INFO - Buffered messages finished
[task 2021-10-22T00:46:54.500Z] 00:46:54     INFO - TEST-UNEXPECTED-FAIL | devtools/shared/commands/target/tests/browser_target_command_various_descriptors.js | Uncaught exception - at resource://devtools/shared/protocol/Front.js:106 - Error: Connection closed, pending request to server0.conn24.content-process15/contentProcessTarget1, type listWorkers failed
[task 2021-10-22T00:46:54.500Z] 00:46:54     INFO - 
[task 2021-10-22T00:46:54.500Z] 00:46:54     INFO - Request stack:
[task 2021-10-22T00:46:54.500Z] 00:46:54     INFO - request@resource://devtools/shared/protocol/Front.js:292:14
[task 2021-10-22T00:46:54.501Z] 00:46:54     INFO - generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:46:19
[task 2021-10-22T00:46:54.501Z] 00:46:54     INFO - listAllWorkerTargets/listChildWorkers/processWorkers<@resource://devtools/client/fronts/root.js:176:40
[task 2021-10-22T00:46:54.501Z] 00:46:54     INFO - Async*listChildWorkers@resource://devtools/client/fronts/root.js:167:19
[task 2021-10-22T00:46:54.501Z] 00:46:54     INFO - async*listAllWorkerTargets@resource://devtools/client/fronts/root.js:186:7
[task 2021-10-22T00:46:54.501Z] 00:46:54     INFO - listAllWorkers@resource://devtools/client/fronts/root.js:113:35
[task 2021-10-22T00:46:54.501Z] 00:46:54     INFO - getWorker@resource://devtools/client/fronts/root.js:280:51
[task 2021-10-22T00:46:54.501Z] 00:46:54     INFO - forWorker@resource://devtools/shared/commands/commands-factory.js:99:46
[task 2021-10-22T00:46:54.501Z] 00:46:54     INFO - async*testWorker@chrome://mochitests/content/browser/devtools/shared/commands/target/tests/browser_target_command_various_descriptors.js:274:42
[task 2021-10-22T00:46:54.501Z] 00:46:54     INFO - @chrome://mochitests/content/browser/devtools/shared/commands/target/tests/browser_target_command_various_descriptors.js:25:9
[task 2021-10-22T00:46:54.501Z] 00:46:54     INFO - Async*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1091:34
[task 2021-10-22T00:46:54.501Z] 00:46:54     INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1131:11
[task 2021-10-22T00:46:54.501Z] 00:46:54     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:939:14
[task 2021-10-22T00:46:54.501Z] 00:46:54     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1041:13
[task 2021-10-22T00:46:54.501Z] 00:46:54     INFO - 
[task 2021-10-22T00:46:54.501Z] 00:46:54     INFO - Stack trace:
[task 2021-10-22T00:46:54.501Z] 00:46:54     INFO - baseFrontClassDestroy@resource://devtools/shared/protocol/Front.js:106:23
[task 2021-10-22T00:46:54.502Z] 00:46:54     INFO - purgeRequests@resource://devtools/client/devtools-client.js:683:15
[task 2021-10-22T00:46:54.502Z] 00:46:54     INFO - onPacket@resource://devtools/client/devtools-client.js:474:12
[task 2021-10-22T00:46:54.502Z] 00:46:54     INFO - send/<@resource://devtools/shared/transport/local-transport.js:68:25
[task 2021-10-22T00:46:54.502Z] 00:46:54     INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:103:22
[task 2021-10-22T00:46:54.502Z] 00:46:54     INFO - DevToolsUtils.executeSoon*exports.executeSoon@resource://devtools/shared/DevToolsUtils.js:54:21
[task 2021-10-22T00:46:54.502Z] 00:46:54     INFO - send@resource://devtools/shared/transport/local-transport.js:56:21
[task 2021-10-22T00:46:54.502Z] 00:46:54     INFO - send@resource://devtools/server/devtools-server-connection.js:99:20
[task 2021-10-22T00:46:54.502Z] 00:46:54     INFO - cancelForwarding@resource://devtools/server/devtools-server-connection.js:321:12
[task 2021-10-22T00:46:54.502Z] 00:46:54     INFO - onClose@resource://devtools/server/connectors/content-process-connector.js:87:20
[task 2021-10-22T00:46:54.502Z] 00:46:54     INFO - connectToContentProcess/</onMessageManagerClose<@resource://devtools/server/connectors/content-process-connector.js:105:11
[task 2021-10-22T00:46:54.502Z] 00:46:54     INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:103:22
[task 2021-10-22T00:46:54.503Z] 00:46:54     INFO - Leaving test bound 
[task 2021-10-22T00:46:54.503Z] 00:46:54     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-10-22T00:46:54.504Z] 00:46:54     INFO - TEST-UNEXPECTED-FAIL | devtools/shared/commands/target/tests/browser_target_command_various_descriptors.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2021-10-22T00:46:54.504Z] 00:46:54     INFO - Stack trace:
[task 2021-10-22T00:46:54.504Z] 00:46:54     INFO - chrome://mochikit/content/browser-test.js:test_ok:1336
[task 2021-10-22T00:46:54.504Z] 00:46:54     INFO - chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:cleanup:340
[task 2021-10-22T00:46:54.505Z] 00:46:54     INFO - GECKO(10164) | MEMORY STAT | vsize 2104423MB | vsizeMaxContiguous 65754768MB | residentFast 357MB | heapAllocated 116MB
[task 2021-10-22T00:46:54.506Z] 00:46:54     INFO - TEST-OK | devtools/shared/commands/target/tests/browser_target_command_various_descriptors.js | took 1890ms
[task 2021-10-22T00:46:54.506Z] 00:46:54     INFO - checking window state
[task 2021-10-22T00:46:54.507Z] 00:46:54     INFO - TEST-START | devtools/shared/commands/target/tests/browser_target_command_watchTargets.js

Comment 1

[BugBot [:suhaib / :marco/ :calixte]]

The Bugbug bot thinks this bug should belong to the 'DevTools::Framework' component, and is moving the bug to that component. Please revert this change in case you think the bot is wrong.

Comment 2

[Alexandru Michis [:malexandru]]

Appeared on tier 1 jobs: https://treeherder.mozilla.org/logviewer?job_id=355767858&repo=mozilla-central&lineNumber=21301

Comment 3

[Intermittent Failures Robot]

4 failures in 3186 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 3
• autoland: 1

Platform and build breakdown:

• windows10-64-2004-ccov-qr: 1
  • opt: 1
• windows10-64-2004-asan-qr: 1
  • opt: 1
• linux1804-64-qr: 2
  • debug: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1737194&startday=2021-10-18&endday=2021-10-24&tree=all

Comment 4

[Intermittent Failures Robot]

14 failures in 2845 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 3
• autoland: 7
• mozilla-central: 4

Platform and build breakdown:

• windows10-64-2004-asan-qr: 8
  • opt: 8
• linux1804-64-qr: 5
  • debug: 5
• windows10-64-2004-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1737194&startday=2021-10-25&endday=2021-10-31&tree=all

Comment 5

[Intermittent Failures Robot]

10 failures in 3030 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• elm: 1
• mozilla-central: 2
• autoland: 6
• mozilla-beta: 1

Platform and build breakdown:

• windows10-64-2004-asan-qr: 7
  • opt: 7
• linux1804-64-qr: 3
  • debug: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1737194&startday=2021-11-01&endday=2021-11-07&tree=all

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 4
• try: 1
• autoland: 4
• mozilla-beta: 1

Platform and build breakdown:

• linux1804-64-qr: 2
  • debug: 2
• windows10-64-2004-asan-qr: 7
  • opt: 7
• windows10-64-2004-ccov-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1737194&startday=2021-11-08&endday=2021-11-14&tree=all

Comment 7

[Alexandre Poirot [:ochameau]]

Attachment: Bug 1737194 - [devtools] Strengthen browser_target_command_various_descriptors.js.

Fix two possible race conditions:

• short lived processes spawn by the test ends up breaking listAllWorkers where the listWorker request
  made against a content process target is throwing because its DevToolsServerConnection is closed.
• the worker created by the test may actually still be visible to WorkerDebuggerManager,
  so use a better technique to catch the WorkerDebugger's id.

Comment 8

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/e65351647b3e
[devtools] Strengthen browser_target_command_various_descriptors.js. r=jdescottes

Comment 9

[Norisz Fay [:noriszfay]]

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

Comment 10

[Intermittent Failures Robot]

18 failures in 3259 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 9
• mozilla-central: 7
• mozilla-beta: 2

Platform and build breakdown:

• windows10-64-2004-asan-qr: 10
  • opt: 10
• windows10-64-2004-shippable-qr: 1
  • opt: 1
• linux1804-64-qr: 2
  • debug: 2
• windows10-64-2004-qr: 3
  • debug: 3
• windows10-64-2004-ccov-qr: 1
  • opt: 1
• macosx1015-64-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1737194&startday=2021-11-15&endday=2021-11-21&tree=all

Comment 11

[Intermittent Failures Robot]

2 failures in 3170 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-release: 2

Platform and build breakdown:

• windows10-64-2004-asan-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1737194&startday=2021-11-29&endday=2021-12-05&tree=all

Comment 12

[Intermittent Failures Robot]

1 failures in 3263 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-release: 1

Platform and build breakdown:

• windows10-64-2004-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1737194&startday=2021-12-13&endday=2021-12-19&tree=all