1514558 - Intermittent devtools/client/debugger/new/test/mochitest/browser_dbg_rr_stepping-02.js | A promise chain failed to handle a rejection: [Exception... "Component not initialized" nsresult: "0xc1f30001 (NS_ERROR_NOT_INITIALIZED)"

Status: RESOLVED FIXED

(DevTools :: Debugger, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: ncsoregi [at] mozilla.com

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

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

01:14:12     INFO - TEST-PASS | devtools/client/debugger/new/test/mochitest/browser_dbg_rr_stepping-02.js | Paused at line 26 expected 26 - 
01:14:12     INFO - Console message: [JavaScript Warning: "Removed unsafe attribute. Element: svg. Attribute: xmlns." {file: "resource://devtools/client/shared/vendor/react-dom.js" line: 5811}]
01:14:12     INFO - Console message: [JavaScript Error: "NS_ERROR_NOT_INITIALIZED" {file: "resource://devtools/client/debugger/new/src/utils/DevToolsUtils.js" line: 23}]
01:14:12     INFO - TEST-PASS | devtools/client/debugger/new/test/mochitest/browser_dbg_rr_stepping-02.js | Paused at line 25 expected 25 - 
01:14:12     INFO - Buffered messages finished
01:14:12     INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/new/test/mochitest/browser_dbg_rr_stepping-02.js | A promise chain failed to handle a rejection: [Exception... "Component not initialized"  nsresult: "0xc1f30001 (NS_ERROR_NOT_INITIALIZED)"  location: "JS frame :: resource://devtools/client/debugger/new/src/utils/DevToolsUtils.js :: executeSoon :: line 23"  data: no] - stack: executeSoon@resource://devtools/client/debugger/new/src/utils/DevToolsUtils.js:23:3
01:14:12     INFO - promiseMiddleware/</</</<@resource://devtools/client/debugger/new/src/actions/utils/middleware/promise.js:46:79
01:14:12     INFO - promiseMiddleware/</</<@resource://devtools/client/debugger/new/src/actions/utils/middleware/promise.js:46:55
01:14:12     INFO - Async*promiseMiddleware/</<@resource://devtools/client/debugger/new/src/actions/utils/middleware/promise.js:46:41
01:14:12     INFO - thunk/</</<@resource://devtools/client/debugger/new/src/actions/utils/middleware/thunk.js:21:100
01:14:12     INFO - dispatch@resource://devtools/client/shared/vendor/redux.js:755:18
01:14:12     INFO - fetchScopes/<@resource://devtools/client/debugger/new/src/actions/pause/fetchScopes.js:27:20
01:14:12     INFO - async*thunk/</</<@resource://devtools/client/debugger/new/src/actions/utils/middleware/thunk.js:21:45
01:14:12     INFO - dispatch@resource://devtools/client/shared/vendor/redux.js:755:18
01:14:12     INFO - paused/<@resource://devtools/client/debugger/new/src/actions/pause/paused.js:84:11
01:14:12     INFO - async*thunk/</</<@resource://devtools/client/debugger/new/src/actions/utils/middleware/thunk.js:21:45
01:14:12     INFO - bindActionCreator/<@resource://devtools/client/shared/vendor/redux.js:644:12
01:14:12     INFO - paused@resource://devtools/client/debugger/new/src/client/firefox/events.js:75:5
01:14:12     INFO - async*eventSource/proto.emit@resource://devtools/shared/client/event-source.js:128:9
01:14:12     INFO - _onThreadState@resource://devtools/shared/client/thread-client.js:694:35
01:14:12     INFO - onPacket@resource://devtools/shared/client/debugger-client.js:812:38
01:14:12     INFO - send/<@resource://devtools/shared/transport/local-transport.js:64:11
01:14:12     INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:14
01:14:12     INFO - DevToolsUtils.executeSoon*exports.executeSoon@resource://devtools/shared/DevToolsUtils.js:57:21
01:14:12     INFO - send@resource://devtools/shared/transport/local-transport.js:58:7
01:14:12     INFO - send@resource://devtools/server/main.js:1031:5
01:14:12     INFO - receiveMessage@resource://devtools/shared/transport/child-transport.js:66:5
01:14:12     INFO - MessageListener.receiveMessage*_addListener@resource://devtools/shared/transport/child-transport.js:40:5
01:14:12     INFO - ready@resource://devtools/shared/transport/child-transport.js:57:5
01:14:12     INFO - connectToFrame/</onActorCreated<@resource://devtools/server/main.js:738:9
01:14:12     INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:14
01:14:12     INFO - MessageListener.receiveMessage*trackMessageManager@resource://devtools/server/main.js:620:11
01:14:12     INFO - connectToFrame/<@resource://devtools/server/main.js:833:7
01:14:12     INFO - connectToFrame@resource://devtools/server/main.js:609:12
01:14:12     INFO - connect@resource://devtools/server/actors/targets/frame-proxy.js:57:21
01:14:12     INFO - async*BrowserTabList.prototype._getActorForBrowser@resource://devtools/server/actors/webbrowser.js:313:10
01:14:12     INFO - BrowserTabList.prototype.getTab@resource://devtools/server/actors/webbrowser.js:351:16
01:14:12     INFO - onGetTab@resource://devtools/server/actors/root.js:345:27
01:14:12     INFO - Async*onPacket@resource://devtools/server/main.js:1324:15
01:14:12     INFO - send/<@resource://devtools/shared/transport/local-transport.js:64:11
01:14:12     INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:14
01:14:12     INFO - DevToolsUtils.executeSoon*exports.executeSoon@resource://devtools/shared/DevToolsUtils.js:57:21
01:14:12     INFO - send@resource://devtools/shared/transport/local-transport.js:58:7
01:14:12     INFO - send@resource://devtools/shared/protocol.js:1391:9
01:14:12     INFO - request@resource://devtools/shared/protocol.js:1409:5
01:14:12     INFO - generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol.js:1531:14
01:14:12     INFO - getTab@resource://devtools/shared/fronts/root.js:210:12
01:14:12     INFO - async*getTab@resource://devtools/shared/client/debugger-client.js:348:12
01:14:12     INFO - createTargetForTab@resource://devtools/client/framework/target.js:90:28
01:14:12     INFO - async*forTab@resource://devtools/client/framework/target.js:39:21
01:14:12     INFO - async*attachDebugger@chrome://mochitests/content/browser/devtools/client/debugger/new/test/mochitest/head.js:77:22
01:14:12     INFO - async*test@chrome://mochitests/content/browser/devtools/client/debugger/new/test/mochitest/browser_dbg_rr_stepping-02.js:15:23
01:14:12     INFO - async*Tester_execTest@chrome://mochikit/content/browser-test.js:1133:9
01:14:12     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:991:9
01:14:12     INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:803:59
01:14:12     INFO - Rejection date: Sun Dec 16 2018 01:14:11 GMT-0800 (Pacific Standard Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 257
01:14:12     INFO - Stack trace:
01:14:12     INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:257
01:14:12     INFO - chrome://mochikit/content/browser-test.js:nextTest:741
01:14:12     INFO - chrome://mochikit/content/browser-test.js:testScope/test_finish/<:1404
01:14:12     INFO - chrome://mochikit/content/browser-test.js:run:1341
01:14:12     INFO - GECKO(2144) | MEMORY STAT | vsize 5082MB | residentFast 996MB | heapAllocated 166MB
01:14:12     INFO - TEST-OK | devtools/client/debugger/new/test/mochitest/browser_dbg_rr_stepping-02.js | took 16896ms

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-central: 2
* mozilla-inbound: 2

Platform breakdown:
* osx-10-10: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1514558&startday=2018-12-10&endday=2018-12-16&tree=all

Comment 2

[Intermittent Failures Robot]

15 failures in 476 pushes (0.032 failures/push) were associated with this bug yesterday.

Repository breakdown:
* autoland: 11
* mozilla-inbound: 2
* mozilla-central: 1
* try: 1

Platform breakdown:
* macosx64-nightly: 1
* osx-10-10: 14

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1514558&startday=2018-12-17&endday=2018-12-17&tree=all

Comment 3

[Cristina Coroiu [:ccoroiu]]

There are these retriggers on autoland: 
https://treeherder.mozilla.org/#/jobs?repo=autoland&resultStatus=pending%2Crunning%2Csuccess%2Ctestfailed%2Cbusted%2Cexception&classifiedState=unclassified&tochange=00a5c85fba1ecfe9e3af8a02040d990d513890e0&searchStr=os%2Cx%2C10.10%2Copt%2Cmochitests%2Cwith%2Ce10s%2Ctest-macosx64%2Fopt-mochitest-devtools-chrome-e10s-1%2Cm-e10s%28dt1%29&fromchange=adf7dac59077630c80c01ec6c322e83c3306b666&group_state=expanded&selectedJob=217622594

Continued on inbound up to: 
https://treeherder.mozilla.org/#/jobs?repo=mozilla-inbound&resultStatus=pending%2Crunning%2Csuccess%2Ctestfailed%2Cbusted%2Cexception&classifiedState=unclassified&searchStr=os%2Cx%2C10.10%2Copt%2Cmochitests%2Cwith%2Ce10s%2Ctest-macosx64%2Fopt-mochitest-devtools-chrome-e10s-1%2Cm-e10s%28dt1%29&fromchange=4666c210aa93dec761ee8893b0b31fa32283ad3b&tochange=b87408f9197d7e259c9ad5228ad925383352a287&group_state=expanded&selectedJob=217624482

Comment 4

[Intermittent Failures Robot]

20 failures in 505 pushes (0.04 failures/push) were associated with this bug yesterday.

Repository breakdown:
* mozilla-central: 2
* autoland: 10
* mozilla-inbound: 8

Platform breakdown:
* osx-10-10: 20

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

Comment 5

[Dorel Luca [:dluca]]

Retriggers show that it started to fail when bug 801304 landed with the following patch: https://hg.mozilla.org/integration/mozilla-inbound/rev/ef37f622e40ebf5b4c23011cab444269b1c6d23e

https://treeherder.mozilla.org/#/jobs?repo=mozilla-inbound&resultStatus=pending%2Crunning%2Csuccess%2Ctestfailed%2Cbusted%2Cexception&classifiedState=unclassified&searchStr=os%2Cx%2C10.10%2Copt%2Cmochitests%2Cwith%2Ce10s%2Ctest-macosx64%2Fopt-mochitest-devtools-chrome-e10s-1%2Cm-e10s%28dt1%29&fromchange=ca19ff7f1891dd8a29300d145747d77d6efdd505&tochange=b87408f9197d7e259c9ad5228ad925383352a287&selectedJob=217824724

Comment 6

[Jim Blandy :jimb]

Brian, this looks like it's in your neighborhood, want to take a look?

Comment 7

[Brian Hackett [Laid off!]]

Attachment: timeout patch

Hmm, the failure from bug 801304 is different from the NS_ERROR_NOT_INITIALIZED failure.  AFAICT the NS_ERROR_NOT_INITIALIZED crash started with the merge.  This patch takes care of the crash which happened on the retrigger for bug 801304 and several of the crashes in following pushes.  When processing a middleman call we were calling objc_lookUpClass in some situations, and if that triggers an unhandled recording divergence then we can hang or crash.  This patch keeps track of the classes of interest in state initialized early on, so that we don't need to call objc_lookUpClass at problematic points.  I don't known if this will fix the NS_ERROR_NOT_INITIALIZED problem, however.

Comment 8

[Andreea Pavel [:apavel]]

Logan, Brian can we add checkin needed or is there something else you need to add?

Comment 9

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/mozilla-inbound/rev/868238eb12aa
Avoid calling objc_lookUpClass when processing middleman calls, r=lsmyth.

Comment 10

[Eliza Balazs [:ebalazs_]]

https://hg.mozilla.org/mozilla-central/rev/868238eb12aa

Comment 11

[Intermittent Failures Robot]

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

This is the #24 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: 3
* try: 1
* mozilla-inbound: 18
* autoland: 33

Platform breakdown:
* osx-10-10: 54
* macosx64-nightly: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1514558&startday=2018-12-17&endday=2018-12-23&tree=all