1452985 - Perma-orange test-verify devtools/client/inspector/test/browser_inspector_highlighter-cssshape_iframe_01.js | A promise chain failed to handle a rejection: Error: Connection closed, pending request to server1.conn0.child1/domstylerule56

Status: RESOLVED INCOMPLETE

(DevTools :: Inspector, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: ncsoregi [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=172851979&repo=mozilla-inbound

https://queue.taskcluster.net/v1/task/VG2AKPVlR2a_TypKhByhqg/runs/0/artifacts/public/logs/live_backing.log

[task 2018-04-10T12:49:18.254Z] 12:49:18     INFO - TEST-UNEXPECTED-FAIL | devtools/client/inspector/test/browser_inspector_highlighter-cssshape_iframe_01.js | A promise chain failed to handle a rejection: Error: Connection closed, pending request to server1.conn0.child1/domstylerule56, type setRuleText failed
[task 2018-04-10T12:49:18.254Z] 12:49:18     INFO - 
[task 2018-04-10T12:49:18.254Z] 12:49:18     INFO - Request stack:
[task 2018-04-10T12:49:18.254Z] 12:49:18     INFO - request@resource://devtools/shared/base-loader.js -> resource://devtools/shared/protocol.js:1279:14
[task 2018-04-10T12:49:18.254Z] 12:49:18     INFO - generateRequestMethods/</frontProto[name]@resource://devtools/shared/base-loader.js -> resource://devtools/shared/protocol.js:1433:14
[task 2018-04-10T12:49:18.254Z] 12:49:18     INFO - StyleRuleFront<.setRuleText<@resource://devtools/shared/base-loader.js -> resource://devtools/shared/fronts/styles.js:282:12
[task 2018-04-10T12:49:18.255Z] 12:49:18     INFO - apply/<@resource://devtools/shared/base-loader.js -> resource://devtools/shared/css/parsing-utils.js:1075:14
[task 2018-04-10T12:49:18.256Z] 12:49:18     INFO - process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:928:23
[task 2018-04-10T12:49:18.256Z] 12:49:18     INFO - walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:812:7
[task 2018-04-10T12:49:18.257Z] 12:49:18     INFO - Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:745:11
[task 2018-04-10T12:49:18.257Z] 12:49:18     INFO - schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7
[task 2018-04-10T12:49:18.258Z] 12:49:18     INFO - Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:461:5
[task 2018-04-10T12:49:18.259Z] 12:49:18     INFO - applyProperties@resource://devtools/shared/base-loader.js -> resource://devtools/client/inspector/rules/models/rule.js:283:9
[task 2018-04-10T12:49:18.260Z] 12:49:18     INFO - setPropertyValue@resource://devtools/shared/base-loader.js -> resource://devtools/client/inspector/rules/models/rule.js:344:5
[task 2018-04-10T12:49:18.260Z] 12:49:18     INFO - setValue@resource://devtools/shared/base-loader.js -> resource://devtools/client/inspector/rules/models/text-property.js:125:5
[task 2018-04-10T12:49:18.261Z] 12:49:18     INFO - commit@resource://devtools/shared/base-loader.js -> resource://devtools/client/shared/widgets/ShapesInContextEditor.js:275:5
[task 2018-04-10T12:49:18.263Z] 12:49:18     INFO - exports.debounce/</timer<@resource://devtools/shared/base-loader.js -> resource://devtools/shared/debounce.js:30:7
[task 2018-04-10T12:49:18.263Z] 12:49:18     INFO - notify@resource://gre/modules/Timer.jsm:44:7
[task 2018-04-10T12:49:18.264Z] 12:49:18     INFO -  - stack: destroy@resource://devtools/shared/base-loader.js -> resource://devtools/shared/protocol.js:1222:23
[task 2018-04-10T12:49:18.265Z] 12:49:18     INFO - destroy@resource://devtools/shared/base-loader.js -> resource://devtools/shared/fronts/styles.js:102:5
[task 2018-04-10T12:49:18.266Z] 12:49:18     INFO - destroy@resource://devtools/shared/base-loader.js -> resource://devtools/shared/protocol.js:877:9
[task 2018-04-10T12:49:18.267Z] 12:49:18     INFO - destroy@resource://devtools/shared/base-loader.js -> resource://devtools/shared/protocol.js:1224:5
[task 2018-04-10T12:49:18.269Z] 12:49:18     INFO - destroy@resource://devtools/shared/base-loader.js -> resource://devtools/shared/fronts/styles.js:39:5
[task 2018-04-10T12:49:18.273Z] 12:49:18     INFO - destroy@resource://devtools/shared/base-loader.js -> resource://devtools/shared/protocol.js:877:9
[task 2018-04-10T12:49:18.274Z] 12:49:18     INFO - destroy@resource://devtools/shared/base-loader.js -> resource://devtools/shared/protocol.js:1224:5
[task 2018-04-10T12:49:18.275Z] 12:49:18     INFO - destroy@resource://devtools/shared/base-loader.js -> resource://devtools/shared/fronts/inspector.js:469:5
[task 2018-04-10T12:49:18.276Z] 12:49:18     INFO - destroyInspector/this._destroyingInspector<@resource://devtools/shared/base-loader.js -> resource://devtools/client/framework/toolbox.js:2573:13
[task 2018-04-10T12:49:18.277Z] 12:49:18     INFO - Async*destroyInspector@resource://devtools/shared/base-loader.js -> resource://devtools/client/framework/toolbox.js:2557:40
[task 2018-04-10T12:49:18.277Z] 12:49:18     INFO - destroy@resource://devtools/shared/base-loader.js -> resource://devtools/client/framework/toolbox.js:2697:22
[task 2018-04-10T12:49:18.278Z] 12:49:18     INFO - closeToolbox@resource://devtools/shared/base-loader.js -> resource://devtools/client/framework/devtools.js:562:11
[task 2018-04-10T12:49:18.279Z] 12:49:18     INFO - async*closeTabAndToolbox@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:429:11
[task 2018-04-10T12:49:18.280Z] 12:49:18     INFO - async*cleanup@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:124:11
[task 2018-04-10T12:49:18.281Z] 12:49:18     INFO - async*Tester.prototype.nextTest<@chrome://mochikit/content/browser-test.js:675:17
[task 2018-04-10T12:49:18.283Z] 12:49:18     INFO - TaskImpl_run@resource://gre/modules/Task.jsm:326:42
[task 2018-04-10T12:49:18.285Z] 12:49:18     INFO - TaskImpl_handleResultValue@resource://gre/modules/Task.jsm:408:7
[task 2018-04-10T12:49:18.285Z] 12:49:18     INFO - TaskImpl_run@resource://gre/modules/Task.jsm:334:15
[task 2018-04-10T12:49:18.286Z] 12:49:18     INFO - TaskImpl_handleResultValue@resource://gre/modules/Task.jsm:408:7
[task 2018-04-10T12:49:18.287Z] 12:49:18     INFO - TaskImpl_run@resource://gre/modules/Task.jsm:334:15
[task 2018-04-10T12:49:18.288Z] 12:49:18     INFO - TaskImpl_handleResultValue@resource://gre/modules/Task.jsm:408:7
[task 2018-04-10T12:49:18.290Z] 12:49:18     INFO - TaskImpl_run@resource://gre/modules/Task.jsm:334:15
[task 2018-04-10T12:49:18.290Z] 12:49:18     INFO - TaskImpl@resource://gre/modules/Task.jsm:275:3
[task 2018-04-10T12:49:18.291Z] 12:49:18     INFO - asyncFunction@resource://gre/modules/Task.jsm:247:14
[task 2018-04-10T12:49:18.292Z] 12:49:18     INFO - testScope/test_finish/<@chrome://mochikit/content/browser-test.js:1372:11
[task 2018-04-10T12:49:18.292Z] 12:49:18     INFO - run@chrome://mochikit/content/browser-test.js:1309:9
[task 2018-04-10T12:49:18.293Z] 12:49:18     INFO - Rejection date: Tue Apr 10 2018 12:49:16 GMT+0000 (UTC) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 257
[task 2018-04-10T12:49:18.294Z] 12:49:18     INFO - Stack trace:
[task 2018-04-10T12:49:18.295Z] 12:49:18     INFO -     resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:257
[task 2018-04-10T12:49:18.296Z] 12:49:18     INFO -     chrome://mochikit/content/browser-test.js:Tester.prototype.nextTest<:714
[task 2018-04-10T12:49:18.297Z] 12:49:18     INFO -     testScope/test_finish/<@chrome://mochikit/content/browser-test.js:1372:11
[task 2018-04-10T12:49:18.298Z] 12:49:18     INFO -     run@chrome://mochikit/content/browser-test.js:1309:9
[task 2018-04-10T12:49:18.299Z] 12:49:18     INFO - Console message: [JavaScript Error: "A promise chain failed to handle a rejection. Did you forget to '.catch', or did you forget to 'return'?
[task 2018-04-10T12:49:18.300Z] 12:49:18     INFO - See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise
[task 2018-04-10T12:49:18.301Z] 12:49:18     INFO - 
[task 2018-04-10T12:49:18.302Z] 12:49:18     INFO - Date: Tue Apr 10 2018 12:49:16 GMT+0000 (UTC)
[task 2018-04-10T12:49:18.303Z] 12:49:18     INFO - Full Message: Error: Connection closed, pending request to server1.conn0.child1/domstylerule56, type setRuleText failed
[task 2018-04-10T12:49:18.304Z] 12:49:18     INFO - 
[task 2018-04-10T12:49:18.304Z] 12:49:18     INFO - Request stack:
[task 2018-04-10T12:49:18.306Z] 12:49:18     INFO - request@resource://devtools/shared/base-loader.js -> resource://devtools/shared/protocol.js:1279:14
[task 2018-04-10T12:49:18.307Z] 12:49:18     INFO - generateRequestMethods/</frontProto[name]@resource://devtools/shared/base-loader.js -> resource://devtools/shared/protocol.js:1433:14
[task 2018-04-10T12:49:18.308Z] 12:49:18     INFO - StyleRuleFront<.setRuleText<@resource://devtools/shared/base-loader.js -> resource://devtools/shared/fronts/styles.js:282:12
[task 2018-04-10T12:49:18.308Z] 12:49:18     INFO - apply/<@resource://devtools/shared/base-loader.js -> resource://devtools/shared/css/parsing-utils.js:1075:14
[task 2018-04-10T12:49:18.310Z] 12:49:18     INFO - process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:928:23
[task 2018-04-10T12:49:18.310Z] 12:49:18     INFO - walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:812:7
[task 2018-04-10T12:49:18.311Z] 12:49:18     INFO - Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:745:11
[task 2018-04-10T12:49:18.312Z] 12:49:18     INFO - schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7
[task 2018-04-10T12:49:18.313Z] 12:49:18     INFO - Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:461:5
[task 2018-04-10T12:49:18.314Z] 12:49:18     INFO - applyProperties@resource://devtools/shared/base-loader.js -> resource://devtools/client/inspector/rules/models/rule.js:283:9
[task 2018-04-10T12:49:18.315Z] 12:49:18     INFO - setPropertyValue@resource://devtools/shared/base-loader.js -> resource://devtools/client/inspector/rules/models/rule.js:344:5
[task 2018-04-10T12:49:18.316Z] 12:49:18     INFO - setValue@resource://devtools/shared/base-loader.js -> resource://devtools/client/inspector/rules/models/text-property.js:125:5
[task 2018-04-10T12:49:18.317Z] 12:49:18     INFO - commit@resource://devtools/shared/base-loader.js -> resource://devtools/client/shared/widgets/ShapesInContextEditor.js:275:5
[task 2018-04-10T12:49:18.318Z] 12:49:18     INFO - exports.debounce/</timer<@resource://devtools/shared/base-loader.js -> resource://devtools/shared/debounce.js:30:7
[task 2018-04-10T12:49:18.318Z] 12:49:18     INFO - notify@resource://gre/modules/Timer.jsm:44:7
[task 2018-04-10T12:49:18.319Z] 12:49:18     INFO - 
[task 2018-04-10T12:49:18.320Z] 12:49:18     INFO - Full Stack: destroy@resource://devtools/shared/base-loader.js -> resource://devtools/shared/protocol.js:1222:23
[task 2018-04-10T12:49:18.321Z] 12:49:18     INFO - destroy@resource://devtools/shared/base-loader.js -> resource://devtools/shared/fronts/styles.js:102:5
[task 2018-04-10T12:49:18.322Z] 12:49:18     INFO - destroy@resource://devtools/shared/base-loader.js -> resource://devtools/shared/protocol.js:877:9
[task 2018-04-10T12:49:18.323Z] 12:49:18     INFO - destroy@resource://devtools/shared/base-loader.js -> resource://devtools/shared/protocol.js:1224:5
[task 2018-04-10T12:49:18.324Z] 12:49:18     INFO - destroy@resource://devtools/shared/base-loader.js -> resource://devtools/shared/fronts/styles.js:39:5
[task 2018-04-10T12:49:18.325Z] 12:49:18     INFO - destroy@resource://devtools/shared/base-loader.js -> resource://devtools/shared/protocol.js:877:9
[task 2018-04-10T12:49:18.326Z] 12:49:18     INFO - destroy@resource://devtools/shared/base-loader.js -> resource://devtools/shared/protocol.js:1224:5
[task 2018-04-10T12:49:18.327Z] 12:49:18     INFO - destroy@resource://devtools/shared/base-loader.js -> resource://devtools/shared/fronts/inspector.js:469:5
[task 2018-04-10T12:49:18.328Z] 12:49:18     INFO - destroyInspector/this._destroyingInspector<@resource://devtools/shared/base-loader.js -> resource://devtools/client/framework/toolbox.js:2573:13
[task 2018-04-10T12:49:18.329Z] 12:49:18     INFO - Async*destroyInspector@resource://devtools/shared/base-loader.js -> resource://devtools/client/framework/toolbox.js:2557:40
[task 2018-04-10T12:49:18.330Z] 12:49:18     INFO - destroy@resource://devtools/shared/base-loader.js -> resource://devtools/client/framework/toolbox.js:2697:22
[task 2018-04-10T12:49:18.331Z] 12:49:18     INFO - closeToolbox@resource://devtools/shared/base-loader.js -> resource://devtools/client/framework/devtools.js:562:11
[task 2018-04-10T12:49:18.331Z] 12:49:18     INFO - async*closeTabAndToolbox@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:429:11
[task 2018-04-10T12:49:18.332Z] 12:49:18     INFO - async*cleanup@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:124:11
[task 2018-04-10T12:49:18.333Z] 12:49:18     INFO - async*Tester.prototype.nextTest<@chrome://mochikit/content/browser-test.js:675:17
[task 2018-04-10T12:49:18.334Z] 12:49:18     INFO - TaskImpl_run@resource://gre/modules/Task.jsm:326:42
[task 2018-04-10T12:49:18.335Z] 12:49:18     INFO - TaskImpl_handleResultValue@resource://gre/modules/Task.jsm:408:7
[task 2018-04-10T12:49:18.336Z] 12:49:18     INFO - TaskImpl_run@resource://gre/modules/Task.jsm:334:15
[task 2018-04-10T12:49:18.337Z] 12:49:18     INFO - TaskImpl_handleResultValue@resource://gre/modules/Task.jsm:408:7
[task 2018-04-10T12:49:18.338Z] 12:49:18     INFO - TaskImpl_run@resource://gre/modules/Task.jsm:334:15
[task 2018-04-10T12:49:18.338Z] 12:49:18     INFO - TaskImpl_handleResultValue@resource://gre/modules/Task.jsm:408:7
[task 2018-04-10T12:49:18.339Z] 12:49:18     INFO - TaskImpl_run@resource://gre/modules/Task.jsm:334:15
[task 2018-04-10T12:49:18.340Z] 12:49:18     INFO - TaskImpl@resource://gre/modules/Task.jsm:275:3
[task 2018-04-10T12:49:18.341Z] 12:49:18     INFO - asyncFunction@resource://gre/modules/Task.jsm:247:14
[task 2018-04-10T12:49:18.342Z] 12:49:18     INFO - testScope/test_finish/<@chrome://mochikit/content/browser-test.js:1372:11
[task 2018-04-10T12:49:18.343Z] 12:49:18     INFO - run@chrome://mochikit/content/browser-test.js:1309:9
[task 2018-04-10T12:49:18.344Z] 12:49:18     INFO - " {file: "resource://devtools/shared/base-loader.js -> resource://devtools/shared/protocol.js" line: 1222 column: 0 source: "1222"}]
[task 2018-04-10T12:49:18.345Z] 12:49:18     INFO - GECKO(10192) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2018-04-10T12:49:18.346Z] 12:49:18     INFO - GECKO(10192) | MEMORY STAT heapAllocated not supported in this build configuration.
[task 2018-04-10T12:49:18.347Z] 12:49:18     INFO - GECKO(10192) | MEMORY STAT | vsize 20973880MB | residentFast 1092MB
[task 2018-04-10T12:49:18.349Z] 12:49:18     INFO - TEST-OK | devtools/client/inspector/test/browser_inspector_highlighter-cssshape_iframe_01.js | took 11656ms

Comment 1

[Razvan Caliman [:rcaliman]]

A fix for this is attached to the patch for Bug 1453428. When that lands, we should stop seeing this intermittent.

Comment 2

[Intermittent Failures Robot]

7 failures in 792 pushes (0.009 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-inbound: 7

Platform breakdown:
* osx-10-10: 3
* linux64: 2
* windows7-32: 1
* windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1452985&startday=2018-04-09&endday=2018-04-15&tree=trunk

Comment 3

[Intermittent Failures Robot]

15 failures in 130 pushes (0.115 failures/push) were associated with this bug yesterday.    

Repository breakdown:
* mozilla-inbound: 6
* autoland: 5
* mozilla-central: 4

Platform breakdown:
* windows10-64-qr: 3
* windows10-64: 3
* osx-10-10: 3
* linux64: 3
* windows7-32: 2
* linux64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1452985&startday=2018-04-18&endday=2018-04-18&tree=trunk

Comment 4

[Intermittent Failures Robot]

20 failures in 167 pushes (0.12 failures/push) were associated with this bug yesterday.    

Repository breakdown:
* mozilla-inbound: 20

Platform breakdown:
* linux64: 20

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1452985&startday=2018-04-19&endday=2018-04-19&tree=trunk

Comment 5

[Gabriel [:gl] (ΦωΦ)]

Someone is gonna ping me about these crash failure so it is better if I forward them in advance.

Comment 6

[Razvan Caliman [:rcaliman]]

A fix for this should have landed with Bug 1453428. If the intermittent persists, I'll revisit.

Comment 7

[Intermittent Failures Robot]

34 failures in 782 pushes (0.043 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:
* mozilla-inbound: 24
* mozilla-central: 5
* autoland: 4
* try: 1

Platform breakdown:
* linux64: 25
* windows10-64-qr: 3
* osx-10-10: 3
* windows10-64: 2
* windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1452985&startday=2018-04-16&endday=2018-04-22&tree=trunk

Comment 8

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup