1576472 - Intermittent toolkit/mozapps/extensions/test/browser/browser_about_debugging_link.js | A promise chain failed to handle a rejection: this.transport is null - stack: send@resource://devtools/server/debugger-server-connection.js:89:5

Status: RESOLVED FIXED

(Toolkit :: Add-ons Manager, defect)

Description

[Treeherder Bug Filer]

Filed by: nbeleuzu [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=263388042&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/ZGDtXvQWTxSL7dSLmT85MQ/runs/0/artifacts/public/logs/live_backing.log

---

[task 2019-08-25T11:23:26.026Z] 11:23:26 INFO - TEST-PASS | toolkit/mozapps/extensions/test/browser/browser_about_debugging_link.js | Should be closing window with correct URI -
[task 2019-08-25T11:23:26.026Z] 11:23:26 INFO - Telling manager window to close
[task 2019-08-25T11:23:26.026Z] 11:23:26 INFO - Manager window close() call returned
[task 2019-08-25T11:23:26.027Z] 11:23:26 INFO - Leaving test bound testAboutDebugging
[task 2019-08-25T11:23:26.028Z] 11:23:26 INFO - Buffered messages finished
[task 2019-08-25T11:23:26.035Z] 11:23:26 INFO - TEST-UNEXPECTED-FAIL | toolkit/mozapps/extensions/test/browser/browser_about_debugging_link.js | A promise chain failed to handle a rejection: this.transport is null - stack: send@resource://devtools/server/debugger-server-connection.js:89:5
[task 2019-08-25T11:23:26.036Z] 11:23:26 INFO - writeError@resource://devtools/shared/protocol/Actor.js:98:15
[task 2019-08-25T11:23:26.036Z] 11:23:26 INFO - generateRequestHandlers/</handler/</<@resource://devtools/shared/protocol/Actor.js:187:30
[task 2019-08-25T11:23:26.037Z] 11:23:26 INFO - promise callbackgenerateRequestHandlers/</handler/<@resource://devtools/shared/protocol/Actor.js:187:14
[task 2019-08-25T11:23:26.037Z] 11:23:26 INFO - _queueResponse@resource://devtools/shared/protocol/Actor.js:107:28
[task 2019-08-25T11:23:26.038Z] 11:23:26 INFO - handler@resource://devtools/shared/protocol/Actor.js:183:14
[task 2019-08-25T11:23:26.038Z] 11:23:26 INFO - onPacket@resource://devtools/server/debugger-server-connection.js:378:58
[task 2019-08-25T11:23:26.039Z] 11:23:26 INFO - send/<@resource://devtools/shared/transport/local-transport.js:70:25
[task 2019-08-25T11:23:26.039Z] 11:23:26 INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:111:22
[task 2019-08-25T11:23:26.039Z] 11:23:26 INFO - DevToolsUtils.executeSoonexports.executeSoon@resource://devtools/shared/DevToolsUtils.js:62:29
[task 2019-08-25T11:23:26.040Z] 11:23:26 INFO - send@resource://devtools/shared/transport/local-transport.js:58:21
[task 2019-08-25T11:23:26.040Z] 11:23:26 INFO - send@resource://devtools/shared/protocol/Front.js:160:30
[task 2019-08-25T11:23:26.040Z] 11:23:26 INFO - request@resource://devtools/shared/protocol/Front.js:178:10
[task 2019-08-25T11:23:26.041Z] 11:23:26 INFO - generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:49:19
[task 2019-08-25T11:23:26.041Z] 11:23:26 INFO - getTarget/this._targetFrontPromise<@resource://devtools/shared/fronts/descriptors/process.js:66:40
[task 2019-08-25T11:23:26.041Z] 11:23:26 INFO - getTarget@resource://devtools/shared/fronts/descriptors/process.js:83:7
[task 2019-08-25T11:23:26.041Z] 11:23:26 INFO - listAllWorkers@resource://devtools/shared/fronts/root.js:103:52
[task 2019-08-25T11:23:26.041Z] 11:23:26 INFO - asynclistWorkers@resource://devtools/client/aboutdebugging/src/modules/client-wrapper.js:157:36
[task 2019-08-25T11:23:26.041Z] 11:23:26 INFO - requestWorkers/<@resource://devtools/client/aboutdebugging/src/actions/debug-targets.js:305:31
[task 2019-08-25T11:23:26.041Z] 11:23:26 INFO - thunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9
[task 2019-08-25T11:23:26.041Z] 11:23:26 INFO - dispatch@resource://devtools/client/shared/vendor/redux.js:755:18
[task 2019-08-25T11:23:26.042Z] 11:23:26 INFO - onWorkersUpdated@resource://devtools/client/aboutdebugging/src/middleware/debug-target-listener.js:23:11
[task 2019-08-25T11:23:26.042Z] 11:23:26 INFO - emit@resource://devtools/shared/event-emitter.js:190:24
[task 2019-08-25T11:23:26.042Z] 11:23:26 INFO - emit@resource://devtools/shared/event-emitter.js:271:18
[task 2019-08-25T11:23:26.042Z] 11:23:26 INFO - onPacket@resource://devtools/shared/protocol/Front.js:214:13
[task 2019-08-25T11:23:26.042Z] 11:23:26 INFO - onPacket@resource://devtools/shared/client/debugger-client.js:593:13
[task 2019-08-25T11:23:26.042Z] 11:23:26 INFO - send/<@resource://devtools/shared/transport/local-transport.js:70:25
[task 2019-08-25T11:23:26.042Z] 11:23:26 INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:111:22
[task 2019-08-25T11:23:26.042Z] 11:23:26 INFO - DevToolsUtils.executeSoonexports.executeSoon@resource://devtools/shared/DevToolsUtils.js:62:29
[task 2019-08-25T11:23:26.042Z] 11:23:26 INFO - send@resource://devtools/shared/transport/local-transport.js:58:21
[task 2019-08-25T11:23:26.043Z] 11:23:26 INFO - send@resource://devtools/server/debugger-server-connection.js:89:20
[task 2019-08-25T11:23:26.043Z] 11:23:26 INFO - onProcessListChanged@resource://devtools/server/actors/root.js:579:15
[task 2019-08-25T11:23:26.043Z] 11:23:26 INFO - _notifyListChanged@resource://devtools/server/actors/process.js:75:12
[task 2019-08-25T11:23:26.043Z] 11:23:26 INFO - _onMessage@resource://devtools/server/actors/process.js:84:10
[task 2019-08-25T11:23:26.043Z] 11:23:26 INFO - MessageListener.receiveMessage*_checkListening@resource://devtools/server/actors/process.js:65:12
[task 2019-08-25T11:23:26.044Z] 11:23:26 INFO - getList@resource://devtools/server/actors/process.js:38:10
[task 2019-08-25T11:23:26.044Z] 11:23:26 INFO - onListProcesses@resource://devtools/server/actors/root.js:556:35
[task 2019-08-25T11:23:26.044Z] 11:23:26 INFO - onPacket@resource://devtools/server/debugger-server-connection.js:378:58
[task 2019-08-25T11:23:26.044Z] 11:23:26 INFO - send/<@resource://devtools/shared/transport/local-transport.js:70:25
[task 2019-08-25T11:23:26.045Z] 11:23:26 INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:111:22
[task 2019-08-25T11:23:26.045Z] 11:23:26 INFO - DevToolsUtils.executeSoon*exports.executeSoon@resource://devtools/shared/DevToolsUtils.js:62:29
[task 2019-08-25T11:23:26.045Z] 11:23:26 INFO - send@resource://devtools/shared/transport/local-transport.js:58:21

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 7
• mozilla-central: 7

Platform breakdown:

• linux64: 14

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1576472&startday=2019-08-19&endday=2019-08-25&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 2
• mozilla-central: 3

Platform breakdown:

• linux64: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1576472&startday=2019-09-02&endday=2019-09-08&tree=all

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 10
• try: 11

Platform breakdown:

• linux64: 21

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

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1
• mozilla-central: 1

Platform breakdown:

• linux64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1576472&startday=2019-09-16&endday=2019-09-22&tree=all

Comment 5

[Intermittent Failures Robot]

11 failures in 4530 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 8
• try: 3

Platform breakdown:

• linux64: 11

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

Comment 6

[Intermittent Failures Robot]

36 failures in 4292 pushes (0.008 failures/push) were associated with this bug in the last 7 days.

This is the #43 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• mozilla-central: 9
• try: 5
• autoland: 21
• mozilla-inbound: 1

Platform breakdown:

• linux64: 36

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

Comment 7

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

This permafails on Linux x64 debug fission since bug 1580561 landed.

Comment 8

[Luca Greco [:rpl] [:luca] [:lgreco]]

Attachment: Bug 1576472 - Fix browser_about_debugging_link.js intermittent failure due to RDP requests pending while closing the tab. r?jdescottes!

browser_about_debugging_link.js is testing the page options that open (or switch) to about:debugging
from about:addons.

This test seems to be failing intermittently because there are pending RDP requests when the about:debugging
tab is being closed once the test is completed.

We already had a similar issue before and at the time daisuke provided us the test helpers currently used
in the test to wait for the tabs, workers and extensions to be populated in the store.

Unfortunately this doesn't seem enough anymore, especially with fission enabled the test is keep
failing because it seems that some new RDP requests are pending when we are closing the tab
(from the stacktrace it seems that the pending requests are triggered by the "processes updates listener").

The waitForRequestsToSettle test helper used in some other about:debugging tests seems to be able to fix
the intermittent failure (trying on linux64 debug locally it passed test-verify multiple times).

Comment 9

[Luca Greco [:rpl] [:luca] [:lgreco]]

The changes from the patches landed from Bug 1580561 shouldn't be able to trigger an higher intermittency in this unrelated test.

I was able to reproduce the failure intermittently using --verify locally, and so I took a look and based on this stack trace that I noticed when I've been able to reproduce it locally:

console.error: "[ACTION FAILED] REQUEST_TABS_FAILURE: Connection closed, pending request to root, type listTabs failed
Request stack:
  request@resource://devtools/shared/protocol/Front.js:167:14
  generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:49:19
  listTabs@resource://devtools/shared/fronts/root.js:317:44
  listTabs@resource://devtools/client/aboutdebugging/src/modules/client-wrapper.js:133:33
  requestTabs/<@resource://devtools/client/aboutdebugging/src/actions/debug-targets.js:209:31
  thunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9
  dispatch@resource://devtools/client/shared/vendor/redux.js:755:18
  onTabsUpdated@resource://devtools/client/aboutdebugging/src/middleware/debug-target-listener.js:19:11
  ...

the failure seems to be due to the reasons described in comment 8 (with attached a patch for feedback/review from the DevTools team).

Comment 10

[Intermittent Failures Robot]

21 failures in 649 pushes (0.032 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 18
• mozilla-central: 2
• try: 1

Platform breakdown:

• linux64: 21

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

Comment 11

[Intermittent Failures Robot]

78 failures in 3801 pushes (0.021 failures/push) were associated with this bug in the last 7 days.

This is the #20 most frequent failure this week.

** This failure happened more than 75 times this week! Resolving this bug is a very high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 1 week, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 61
• mozilla-central: 13
• try: 3
• mozilla-inbound: 1

Platform breakdown:

• linux64: 78

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

Comment 12

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/c157c41fcb26
Fix browser_about_debugging_link.js intermittent failure due to RDP requests pending while closing the tab. r=jdescottes

Comment 13

[Natalia Csoregi [:nataliaCs]]

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

Comment 14

[Intermittent Failures Robot]

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

Repository breakdown:

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

Platform breakdown:

• linux64: 11

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

Comment 15

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• linux64: 1

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