1570476 - Intermittent devtools/client/webconsole/test/mochitest/browser_webconsole_network_messages_stacktrace_console_initiated_request.js | A promise chain failed to handle a rejection: 'getStackTrace' active request packet to 'server0.conn296.netEvent4' can

Status: RESOLVED FIXED

(DevTools :: Console, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: rgurzau [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=259200489&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/apCpbHeeSXK9LogkHIswZg/runs/0/artifacts/public/logs/live_backing.log

---

task 2019-07-31T16:22:08.894Z] 16:22:08 INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_network_messages_stacktrace_console_initiated_request.js | Network message found. -
[task 2019-07-31T16:22:08.894Z] 16:22:08 INFO - Expand the network message
[task 2019-07-31T16:22:08.900Z] 16:22:08 INFO - Click on XHR message and wait for the network detail panel to be displayed
[task 2019-07-31T16:22:08.900Z] 16:22:08 INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_network_messages_stacktrace_console_initiated_request.js | StackTrace tab is available -
[task 2019-07-31T16:22:08.900Z] 16:22:08 INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_network_messages_stacktrace_console_initiated_request.js | There's the expected frames -
[task 2019-07-31T16:22:08.900Z] 16:22:08 INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_network_messages_stacktrace_console_initiated_request.js | The stacktrace does not have devtools' internal frames -
[task 2019-07-31T16:22:08.900Z] 16:22:08 INFO - Leaving test bound task
[task 2019-07-31T16:22:08.900Z] 16:22:08 INFO - Buffered messages logged at 16:22:08
[task 2019-07-31T16:22:08.902Z] 16:22:08 INFO - Removing tab.
[task 2019-07-31T16:22:08.902Z] 16:22:08 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2019-07-31T16:22:08.903Z] 16:22:08 INFO - Got event: 'TabClose' on [object XULElement].
[task 2019-07-31T16:22:08.903Z] 16:22:08 INFO - Tab removed and finished closing
[task 2019-07-31T16:22:08.903Z] 16:22:08 INFO - Console message: [JavaScript Error: "uncaught exception: Object"]
[task 2019-07-31T16:22:08.903Z] 16:22:08 INFO - Console message: [JavaScript Error: "Error: Error while calling method getStackTrace: 'getStackTrace' active request packet to 'server0.conn296.netEvent4' can't be sent as the connection just closed." {file: "resource://devtools/client/netmonitor/src/connector/firefox-data-provider.js" line: 615}]
[task 2019-07-31T16:22:08.904Z] 16:22:08 INFO - Buffered messages finished
[task 2019-07-31T16:22:08.904Z] 16:22:08 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/mochitest/browser_webconsole_network_messages_stacktrace_console_initiated_request.js | A promise chain failed to handle a rejection: 'getStackTrace' active request packet to 'server0.conn296.netEvent4' can't be sent as the connection just closed. - stack: listenerJson@resource://devtools/shared/client/debugger-client.js:342:11
[task 2019-07-31T16:22:08.904Z] 16:22:08 INFO - emit@resource://devtools/shared/event-emitter.js:190:24
[task 2019-07-31T16:22:08.904Z] 16:22:08 INFO - emit@resource://devtools/shared/event-emitter.js:271:18
[task 2019-07-31T16:22:08.904Z] 16:22:08 INFO - reject@resource://devtools/shared/client/debugger-client.js:816:15
[task 2019-07-31T16:22:08.904Z] 16:22:08 INFO - purgeRequests/<@resource://devtools/shared/client/debugger-client.js:837:47
[task 2019-07-31T16:22:08.904Z] 16:22:08 INFO - purgeRequests@resource://devtools/shared/client/debugger-client.js:837:28
[task 2019-07-31T16:22:08.904Z] 16:22:08 INFO - onClosed@resource://devtools/shared/client/debugger-client.js:763:10
[task 2019-07-31T16:22:08.904Z] 16:22:08 INFO - close@resource://devtools/shared/transport/local-transport.js:171:20
[task 2019-07-31T16:22:08.904Z] 16:22:08 INFO - close@resource://devtools/shared/transport/local-transport.js:167:13
[task 2019-07-31T16:22:08.904Z] 16:22:08 INFO - close@resource://devtools/shared/transport/local-transport.js:167:13
[task 2019-07-31T16:22:08.904Z] 16:22:08 INFO - cleanup@resource://devtools/shared/client/debugger-client.js:216:27
[task 2019-07-31T16:22:08.904Z] 16:22:08 INFO - close/promise<@resource://devtools/shared/client/debugger-client.js:232:7
[task 2019-07-31T16:22:08.904Z] 16:22:08 INFO - close@resource://devtools/shared/client/debugger-client.js:209:21
[task 2019-07-31T16:22:08.905Z] 16:22:08 INFO - TargetMixin/destroy/this._destroyer<@resource://devtools/shared/fronts/targets/target-mixin.js:581:32
[task 2019-07-31T16:22:08.905Z] 16:22:08 INFO - asyncdestroy@resource://devtools/shared/fronts/targets/target-mixin.js:604:9
[task 2019-07-31T16:22:08.905Z] 16:22:08 INFO - destroy@resource://devtools/shared/fronts/targets/browsing-context.js:148:27
[task 2019-07-31T16:22:08.905Z] 16:22:08 INFO - _destroyToolbox/onceDestroyed</<@resource://devtools/client/framework/toolbox.js:3626:27
[task 2019-07-31T16:22:08.905Z] 16:22:08 INFO - async_destroyToolbox/onceDestroyed<@resource://devtools/client/framework/toolbox.js:3585:12
[task 2019-07-31T16:22:08.905Z] 16:22:08 INFO - _destroyToolbox@resource://devtools/client/framework/toolbox.js:3581:27
[task 2019-07-31T16:22:08.905Z] 16:22:08 INFO - destroy@resource://devtools/client/framework/toolbox.js:3457:28

Comment 1

[Cristina Coroiu [:ccoroiu]]

Started retriggers:
https://treeherder.mozilla.org/#/jobs?repo=autoland&searchStr=os%2Cx%2C10.14%2Cdebug%2Cmochitests%2Ctest-macosx1014-64%2Fdebug-mochitest-devtools-chrome-e10s-1%2Cm%28dt1%29&tochange=ab146dc0cde6d3dbcaf7bf914e204af2a0faa4b7&fromchange=946723ace24effc19de6b7f9f0744d2018c31185&selectedJob=259802410

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 17
• mozilla-central: 1
• try: 2
• mozilla-inbound: 2

Platform breakdown:

• macosx1014-64: 11
• windows10-64: 11

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

Comment 3

[Intermittent Failures Robot]

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

** 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:

• autoland: 28
• try: 1
• mozilla-inbound: 4
• mozilla-central: 3

Platform breakdown:

• macosx1014-64: 21
• windows10-64: 15

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

Comment 4

[Intermittent Failures Robot]

34 failures in 4839 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

** 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:

• autoland: 32
• mozilla-inbound: 1
• mozilla-central: 1

Platform breakdown:

• windows10-64: 6
• macosx1014-64: 28

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

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• macosx1014-64: 2

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

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 8
• mozilla-inbound: 1

Platform breakdown:

• windows10-64: 2
• macosx1014-64: 7

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

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 11
• mozilla-inbound: 3
• mozilla-central: 2
• try: 1

Platform breakdown:

• macosx1014-64: 17

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

Comment 8

[Razvan Maries]

There are 23 total failures in the last 7 days: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2019-09-05&endday=2019-09-12&tree=trunk&bug=1570476

Recent failure log : https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=266246240&repo=autoland&lineNumber=34356

[task 2019-09-12T07:33:13.334Z] 07:33:13 INFO - TEST-START | devtools/client/webconsole/test/browser/browser_webconsole_network_messages_stacktrace_console_initiated_request.js
[task 2019-09-12T07:33:19.555Z] 07:33:19 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_webconsole_network_messages_stacktrace_console_initiated_request.js | A promise chain failed to handle a rejection: 'getStackTrace' active request packet to 'server0.conn214.netEvent4' can't be sent as the connection just closed. - stack: listenerJson@resource://devtools/shared/client/debugger-client.js:341:17
[task 2019-09-12T07:33:19.555Z] 07:33:19 INFO - emit@resource://devtools/shared/event-emitter.js:190:24
[task 2019-09-12T07:33:19.555Z] 07:33:19 INFO - emit@resource://devtools/shared/event-emitter.js:271:18
[task 2019-09-12T07:33:19.555Z] 07:33:19 INFO - reject@resource://devtools/shared/client/debugger-client.js:815:15
[task 2019-09-12T07:33:19.555Z] 07:33:19 INFO - purgeRequests/<@resource://devtools/shared/client/debugger-client.js:836:53
[task 2019-09-12T07:33:19.555Z] 07:33:19 INFO - purgeRequests@resource://devtools/shared/client/debugger-client.js:836:28
[task 2019-09-12T07:33:19.555Z] 07:33:19 INFO - onClosed@resource://devtools/shared/client/debugger-client.js:762:10
[task 2019-09-12T07:33:19.555Z] 07:33:19 INFO - close@resource://devtools/shared/transport/local-transport.js:171:20
[task 2019-09-12T07:33:19.555Z] 07:33:19 INFO - close@resource://devtools/shared/transport/local-transport.js:167:13
[task 2019-09-12T07:33:19.555Z] 07:33:19 INFO - close@resource://devtools/shared/transport/local-transport.js:167:13
[task 2019-09-12T07:33:19.555Z] 07:33:19 INFO - cleanup@resource://devtools/shared/client/debugger-client.js:215:27
[task 2019-09-12T07:33:19.555Z] 07:33:19 INFO - close/promise<@resource://devtools/shared/client/debugger-client.js:231:7
[task 2019-09-12T07:33:19.556Z] 07:33:19 INFO - close@resource://devtools/shared/client/debugger-client.js:208:21
[task 2019-09-12T07:33:19.556Z] 07:33:19 INFO - TargetMixin/destroy/this._destroyer<@resource://devtools/shared/fronts/targets/target-mixin.js:457:32
[task 2019-09-12T07:33:19.556Z] 07:33:19 INFO - asyncdestroy@resource://devtools/shared/fronts/targets/target-mixin.js:480:9
[task 2019-09-12T07:33:19.556Z] 07:33:19 INFO - destroy@resource://devtools/shared/fronts/targets/browsing-context.js:148:27
[task 2019-09-12T07:33:19.556Z] 07:33:19 INFO - _destroyToolbox/onceDestroyed</<@resource://devtools/client/framework/toolbox.js:3634:27
[task 2019-09-12T07:33:19.556Z] 07:33:19 INFO - promise callback_destroyToolbox/onceDestroyed<@resource://devtools/client/framework/toolbox.js:3591:12
[task 2019-09-12T07:33:19.556Z] 07:33:19 INFO - _destroyToolbox@resource://devtools/client/framework/toolbox.js:3587:27
[task 2019-09-12T07:33:19.556Z] 07:33:19 INFO - destroy@resource://devtools/client/framework/toolbox.js:3463:28
[task 2019-09-12T07:33:19.556Z] 07:33:19 INFO - closeToolbox@resource://devtools/client/framework/devtools.js:673:19
[task 2019-09-12T07:33:19.556Z] 07:33:19 INFO - asynccloseTabAndToolbox@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:500:23
[task 2019-09-12T07:33:19.556Z] 07:33:19 INFO - asynccleanup@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:161:11
[task 2019-09-12T07:33:19.556Z] 07:33:19 INFO - nextTest@chrome://mochikit/content/browser-test.js:860:35
[task 2019-09-12T07:33:19.556Z] 07:33:19 INFO - async*testScope/test_finish/<@chrome://mochikit/content/browser-test.js:1732:25
[task 2019-09-12T07:33:19.557Z] 07:33:19 INFO - run@chrome://mochikit/content/browser-test.js:1647:9
[task 2019-09-12T07:33:19.557Z] 07:33:19 INFO - Rejection date: Thu Sep 12 2019 07:33:19 GMT+0000 (Greenwich Mean Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 263

Nicolas, could you assign this to someone?

Comment 9

[Nicolas Chevobbe [:nchevobbe]]

Yes, let me have a look

Comment 10

[Nicolas Chevobbe [:nchevobbe]]

Attachment: Bug 1570476 - Don't fetch the request stacktrace in StackTracePanel's componentWillReceiveProps if the request won't change. r=Honza.

This was causing an intermittent in console test because
multiple requests were sent to the server, and sometimes,
some of them after the connection being closed, which
was making the test fail.
With this patch, we only try to fetch a new stacktrace
on componenetWillReceiveProps if the component will have
to deal with a new request.

Comment 11

[Nicolas Chevobbe [:nchevobbe]]

TRY looks promising https://treeherder.mozilla.org/#/jobs?repo=try&selectedJob=266484852&revision=c79fc3a37bd85a3c95076c3f00a23679cbda26e4

Comment 12

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/cfc5a1ad7e10
Don't fetch the request stacktrace in StackTracePanel's componentWillReceiveProps if the request won't change. r=Honza.

Comment 13

[Bogdan Tara[:bogdan_tara | bogdant]]

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

Comment 14

[Intermittent Failures Robot]

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

Repository breakdown:

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

Platform breakdown:

• macosx1014-64: 20

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