Closed Bug 1441545 Opened 3 years ago Closed 1 year ago

Intermittent devtools/client/debugger/new/test/mochitest/browser_dbg-babel-scopes.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. -

Categories

(DevTools :: Debugger, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED FIXED

People

(Reporter: intermittent-bug-filer, Assigned: aiakab)

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell disabled])

Attachments

(1 file, 3 obsolete files)

Filed by: ccoroiu [at] mozilla.com

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

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

[task 2018-02-27T11:13:59.550Z] 11:13:59     INFO - TEST-PASS | devtools/client/debugger/new/test/mochitest/browser_dbg-babel-scopes.js | Breakpoint reverted - 
[task 2018-02-27T11:13:59.551Z] 11:13:59     INFO - Resuming
[task 2018-02-27T11:13:59.551Z] 11:13:59     INFO - Buffered messages logged at 11:13:57
[task 2018-02-27T11:13:59.552Z] 11:13:59     INFO - TEST-PASS | devtools/client/debugger/new/test/mochitest/browser_dbg-babel-scopes.js | Ran tests for webpack-modules-es6 at line 17 column 2 - 
[task 2018-02-27T11:13:59.553Z] 11:13:59     INFO - Leaving test bound 
[task 2018-02-27T11:13:59.553Z] 11:13:59     INFO - Buffered messages logged at 11:13:58
[task 2018-02-27T11:13:59.554Z] 11:13:59     INFO - Removing tab.
[task 2018-02-27T11:13:59.554Z] 11:13:59     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2018-02-27T11:13:59.555Z] 11:13:59     INFO - Got event: 'TabClose' on [object XULElement].
[task 2018-02-27T11:13:59.556Z] 11:13:59     INFO - Tab removed and finished closing
[task 2018-02-27T11:13:59.556Z] 11:13:59     INFO - Buffered messages finished
[task 2018-02-27T11:13:59.557Z] 11:13:59     INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/new/test/mochitest/browser_dbg-babel-scopes.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. - 
[task 2018-02-27T11:13:59.558Z] 11:13:59     INFO - GECKO(1056) | MEMORY STAT | vsize 2602MB | residentFast 732MB | heapAllocated 177MB
[task 2018-02-27T11:13:59.558Z] 11:13:59     INFO - TEST-OK | devtools/client/debugger/new/test/mochitest/browser_dbg-babel-scopes.js | took 314232ms
[task 2018-02-27T11:13:59.559Z] 11:13:59     INFO - GECKO(1056) | ++DOCSHELL 0x7fad8e32f000 == 2 [pid = 1171] [id = {a5670be2-c96c-4700-944b-432adbed7734}]
[task 2018-02-27T11:13:59.560Z] 11:13:59     INFO - GECKO(1056) | ++DOMWINDOW == 3 (0x7fad8ca12c00) [pid = 1171] [serial = 8] [outer = (nil)]
[task 2018-02-27T11:13:59.560Z] 11:13:59     INFO - GECKO(1056) | ++DOMWINDOW == 4 (0x7fad8ca13800) [pid = 1171] [serial = 9] [outer = 0x7fad8ca12c00]
[task 2018-02-27T11:13:59.561Z] 11:13:59     INFO - checking window state
[task 2018-02-27T11:13:59.561Z] 11:13:59     INFO - TEST-START | devtools/client/debugger/new/test/mochitest/browser_dbg-babel-stepping.js
[task 2018-02-27T11:13:59.646Z] 11:13:59     INFO - GECKO(1056) | ++DOCSHELL 0x7f5261a84800 == 1 [pid = 1198] [id = {ce9ed460-dcc1-45e3-b1ec-4f8e53b0c813}]
[task 2018-02-27T11:13:59.648Z] 11:13:59     INFO - GECKO(1056) | ++DOMWINDOW == 1 (0x7f5261554c00) [pid = 1198] [serial = 3] [outer = (nil)]
[task 2018-02-27T11:13:59.766Z] 11:13:59     INFO - GECKO(1056) | ++DOMWINDOW == 2 (0x7f5261555c00) [pid = 1198] [serial = 4] [outer = 0x7f5261554c00]
[task 2018-02-27T11:13:59.984Z] 11:13:59     INFO - GECKO(1056) | [Child 1198, Main Thread] WARNING: site security information will not be persisted: file /builds/worker/workspace/build/src/security/manager/ssl/nsSiteSecurityService.cpp, line 553
[task 2018-02-27T11:14:00.346Z] 11:14:00     INFO - GECKO(1056) | --DOCSHELL 0x7f63a1619800 == 4 [pid = 1056] [id = {c0d777f4-239d-4a6c-a39e-b422e1826eb0}]
[task 2018-02-27T11:14:00.346Z] 11:14:00     INFO - GECKO(1056) | ++DOMWINDOW == 3 (0x7f526155a800) [pid = 1198] [serial = 5] [outer = 0x7f5261554c00]
[task 2018-02-27T11:14:04.608Z] 11:14:04     INFO - GECKO(1056) | ++DOCSHELL 0x7f63a08d9000 == 5 [pid = 1056] [id = {4ce0fdf3-ff7a-4229-9fdc-493b47b879b7}]
[task 2018-02-27T11:14:04.609Z] 11:14:04     INFO - GECKO(1056) | ++DOMWINDOW == 15 (0x7f63a1d0ac00) [pid = 1056] [serial = 30] [outer = (nil)]
[task 2018-02-27T11:14:04.609Z] 11:14:04     INFO - GECKO(1056) | ++DOMWINDOW == 16 (0x7f63a2c72400) [pid = 1056] [serial = 31] [outer = 0x7f63a1d0ac00]
[task 2018-02-27T11:14:04.832Z] 11:14:04     INFO - GECKO(1056) | ++DOMWINDOW == 17 (0x7f63a140d400) [pid = 1056] [serial = 32] [outer = 0x7f63a1d0ac00]
[task 2018-02-27T11:14:05.022Z] 11:14:05     INFO - GECKO(1056) | [Parent 1056, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004001: file /builds/worker/workspace/build/src/dom/xul/nsXULPrototypeCache.cpp, line 353
[task 2018-02-27T11:14:05.507Z] 11:14:05     INFO - GECKO(1056) | ++DOCSHELL 0x7f63a75af000 == 6 [pid = 1056] [id = {fa496c9c-8e3c-45d7-99d5-0bb24d25c5aa}]
[task 2018-02-27T11:14:05.508Z] 11:14:05     INFO - GECKO(1056) | ++DOMWINDOW == 18 (0x7f63a14bc400) [pid = 1056] [serial = 33] [outer = (nil)]
[task 2018-02-27T11:14:05.509Z] 11:14:05     INFO - GECKO(1056) | ++DOMWINDOW == 19 (0x7f63a14bfc00) [pid = 1056] [serial = 34] [outer = 0x7f63a14bc400]
[task 2018-02-27T11:14:07.206Z] 11:14:07     INFO - GECKO(1056) | --DOCSHELL 0x7fad8e326800 == 1 [pid = 1171] [id = {fd0be422-3db9-40cb-bc4f-dbf90f61be69}]
[task 2018-02-27T11:14:07.848Z] 11:14:07     INFO - GECKO(1056) | --DOCSHELL 0x7fb80315f000 == 0 [pid = 1138] [id = {38d4a106-2007-46eb-b0eb-51c472a1b647}]
[task 2018-02-27T11:14:08.199Z] 11:14:08     INFO - GECKO(1056) | --DOMWINDOW == 3 (0x7fad8fb1fc00) [pid = 1171] [serial = 5] [outer = (nil)] [url = http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/doc-babel.html]
[task 2018-02-27T11:14:08.437Z] 11:14:08     INFO - GECKO(1056) | --DOMWINDOW == 1 (0x7fb803f3c000) [pid = 1138] [serial = 6] [outer = (nil)] [url = about:blank]
[task 2018-02-27T11:14:09.294Z] 11:14:09     INFO - GECKO(1056) | [ACTION] ADD_SOURCES  - {"type":"ADD_SOURCES","sources":[{"id":"server1.conn3.child1/source26","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/babel/polyfill-bundle.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":null,"isBlackBoxed":false,"loadedState":"unloaded"}]}
[task 2018-02-27T11:14:09.442Z] 11:14:09     INFO - GECKO(1056) | [ACTION] ADD_SOURCES  - {"type":"ADD_SOURCES","sources":[{"id":"server1.conn3.child1/source27","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/babel/fixtures/classes/output.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":"output.js.map","isBlackBoxed":false,"loadedState":"unloaded"},{"id":"server1.conn3.child1/source28","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/babel/fixtures/webpack-modules/output.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":"output.js.map","isBlackBoxed":false,"loadedState":"unloaded"},{"id":"server1.conn3.child1/source29","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/babel/fixtures/step-over-regenerator-await/output.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":"output.js.map","isBlackBoxed":false,"loadedState":"unloaded"},{"id":"server1.conn3.child1/source30","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/babel/fixtures/switches/output.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":"output.js.map","isBlackBoxed":false,"loadedState":"unloaded"},{"id":"server1.conn3.child1/source31","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/babel/fixtures/for-of/output.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":"output.js.map","isBlackBoxed":false,"loadedState":"unloaded"},{"id":"server1.conn3.child1/source32","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/babel/fixtures/functions/output.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":"output.js.map","isBlackBoxed":false,"loadedState":"unloaded"},{"id":"server1.conn3.child1/source33","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/babel/fixtures/imported-bindings/output.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":"output.js.map","isBlackBoxed":false,"loadedState":"unloaded"},{"id":"server1.conn3.child1/source34","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/babel/fixtures/non-modules/output.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":"output.js.map","isBlackBoxed":false,"loadedState":"unloaded"},{"id":"server1.conn3.child1/source35","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/babel/fixtures/shadowed-vars/output.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":"output.js.map","isBlackBoxed":false,"loadedState":"unloaded"},{"id":"server1.conn3.child1/source36","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/babel/fixtures/step-over-for-of/output.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":"output.js.map","isBlackBoxed":false,"loadedState":"unloaded"},{"id":"server1.conn3.child1/source37","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/babel/fixtures/step-over-for-of-array/output.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":"output.js.map","isBlackBoxed":false,"loadedState":"unloaded"},{"id":"server1.conn3.child1/source38","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/babel/fixtures/step-over-for-of-array-closure/output.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":"output.js.map","isBlackBoxed":false,"loadedState":"unloaded"},{"id":"server1.conn3.child1/source39","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/babel/fixtures/commonjs/output.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":"output.js.map","isBlackBoxed":false,"loadedState":"unloaded"},{"id":"server1.conn3.child1/source40","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/babel/fixtures/for-loops/output.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":"output.js.map","isBlackBoxed":false,"loadedState":"unloaded"},{"id":"server1.conn3.child1/source41","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/babel/fixtures/step-over-for-of-closure/output.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":"output.js.map","isBlackBoxed":false,"loadedState":"unloaded"},{"id":"server1.conn3.child1/source42","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/babel/fixtures/step-over-function-params/output.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":"output.js.map","isBlackBoxed":false,"loadedState":"unloaded"},{"id":"server1.conn3.child1/source43","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/babel/fixtures/this-arguments-bindings/output.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":"output.js.map","isBlackBoxed":false,"loadedState":"unloaded"},{"id":"server1.conn3.child1/source44","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/babel/fixtures/try-catches/output.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":"output.js.map","isBlackBoxed":false,"loadedState":"unloaded"},{"id":"server1.conn3.child1/source45","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/babel/fixtures/modules/output.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":"output.js.map","isBlackBoxed":false,"loadedState":"unloaded"},{"id":"server1.conn3.child1/source46","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/babel/fixtures/webpack-modules-es6/output.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":"output.js.map","isBlackBoxed":false,"loadedState":"unloaded"}]}
[task 2018-02-27T11:14:09.728Z] 11:14:09     INFO - GECKO(1056) | [ACTION] UPDATE_SOURCE  - {"type":"UPDATE_SOURCE","source":{"id":"server1.conn3.child1/source26","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/babel/polyfill-bundle.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":"","isBlackBoxed":false,"loadedState":"unloaded"}}
[task 2018-02-27T11:14:09.744Z] 11:14:09     INFO - GECKO(1056) | [ACTION] SET_WORKERS  - {"type":"SET_WORKERS","workers":[]}
[task 2018-02-27T11:14:09.748Z] 11:14:09     INFO - GECKO(1056) | [ACTION] CONNECT  - {"type":"CONNECT","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/doc-babel.html"}
[task 2018-02-27T11:14:10.436Z] 11:14:10     INFO - GECKO(1056) | console.error: "Warning: Expressions has a method called shouldComponentUpdate(). shouldComponentUpdate should not be used when extending React.PureComponent. Please extend React.Component if shouldComponentUpdate is used."
[task 2018-02-27T11:14:10.492Z] 11:14:10     INFO - GECKO(1056) | console.error: "Warning: Breakpoints has a method called shouldComponentUpdate(). shouldComponentUpdate should not be used when extending React.PureComponent. Please extend React.Component if shouldComponentUpdate is used."
[task 2018-02-27T11:14:10.513Z] 11:14:10     INFO - GECKO(1056) | [ACTION] SET_EXPANDED_STATE  - {"type":"SET_EXPANDED_STATE","expanded":{}}
[task 2018-02-27T11:14:11.255Z] 11:14:11     INFO - GECKO(1056) | [Parent 1056, Main Thread] WARNING: Please do not use mouseenter/leave events in chrome. They are slower than mouseover/out!: '!nsContentUtils::IsChromeDoc(d)', file /builds/worker/workspace/build/src/dom/events/EventListenerManager.cpp, line 390
[task 2018-02-27T11:14:11.257Z] 11:14:11     INFO - GECKO(1056) | [Parent 1056, Main Thread] WARNING: Please do not use mouseenter/leave events in chrome. They are slower than mouseover/out!: '!nsContentUtils::IsChromeDoc(d)', file /builds/worker/workspace/build/src/dom/events/EventListenerManager.cpp, line 390
[task 2018-02-27T11:14:11.279Z] 11:14:11     INFO - GECKO(1056) | [ACTION] SET_ORIENTATION  - {"type":"SET_ORIENTATION","orientation":"horizontal"}
[task 2018-02-27T11:14:11.446Z] 11:14:11     INFO - GECKO(1056) | --DOMWINDOW == 2 (0x7fad8fb29800) [pid = 1171] [serial = 7] [outer = (nil)] [url = http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/doc-babel.html]
[task 2018-02-27T11:14:12.155Z] 11:14:12     INFO - GECKO(1056) | [Parent 1056, Main Thread] WARNING: NS_ENSURE_TRUE(aSecondURI) failed: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 98
[task 2018-02-27T11:14:12.159Z] 11:14:12     INFO - GECKO(1056) | [Parent 1056, Main Thread] WARNING: NS_ENSURE_TRUE(aSecondURI) failed: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 98
[task 2018-02-27T11:14:12.236Z] 11:14:12     INFO - GECKO(1056) | [Parent 1056, Main Thread] WARNING: NS_ENSURE_TRUE(aSecondURI) failed: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 98
[task 2018-02-27T11:14:12.333Z] 11:14:12     INFO - GECKO(1056) | [Parent 1056, Main Thread] WARNING: NS_ENSURE_TRUE(aSecondURI) failed: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 98
[task 2018-02-27T11:14:12.416Z] 11:14:12     INFO - GECKO(1056) | --DOMWINDOW == 0 (0x7fb803f3f800) [pid = 1138] [serial = 7] [outer = (nil)] [url = about:blank]
Since this bug was crated, 3 days ago, there have been 36 failures.

Most of the failures are on linux64-ccov and a few of them on linux64-stylo-disabled.
Affected build types: opt and debug.

An example of a recent log file:
https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-central&job_id=165403328&lineNumber=3081 

And the relevant part of the log:
[task 2018-03-02T01:13:21.947Z] 01:13:21     INFO - TEST-PASS | devtools/client/debugger/new/test/mochitest/browser_dbg-babel-scopes.js | Breakpoint reverted - 
[task 2018-03-02T01:13:21.948Z] 01:13:21     INFO - Resuming
[task 2018-03-02T01:13:21.949Z] 01:13:21     INFO - Buffered messages logged at 01:13:20
[task 2018-03-02T01:13:21.949Z] 01:13:21     INFO - TEST-PASS | devtools/client/debugger/new/test/mochitest/browser_dbg-babel-scopes.js | Ran tests for webpack-modules-es6 at line 17 column 2 - 
[task 2018-03-02T01:13:21.950Z] 01:13:21     INFO - Leaving test bound 
[task 2018-03-02T01:13:21.951Z] 01:13:21     INFO - Buffered messages logged at 01:13:21
[task 2018-03-02T01:13:21.951Z] 01:13:21     INFO - Removing tab.
[task 2018-03-02T01:13:21.952Z] 01:13:21     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2018-03-02T01:13:21.953Z] 01:13:21     INFO - Got event: 'TabClose' on [object XULElement].
[task 2018-03-02T01:13:21.953Z] 01:13:21     INFO - Tab removed and finished closing
[task 2018-03-02T01:13:21.954Z] 01:13:21     INFO - Buffered messages finished
[task 2018-03-02T01:13:21.954Z] 01:13:21     INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/new/test/mochitest/browser_dbg-babel-scopes.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. - 
[task 2018-03-02T01:13:21.955Z] 01:13:21     INFO - GECKO(1056) | MEMORY STAT | vsize 2630MB | residentFast 723MB | heapAllocated 171MB
[task 2018-03-02T01:13:21.956Z] 01:13:21     INFO - TEST-OK | devtools/client/debugger/new/test/mochitest/browser_dbg-babel-scopes.js | took 286162ms
[task 2018-03-02T01:13:21.956Z] 01:13:21     INFO - GECKO(1056) | ++DOCSHELL 0x7fbcd0f0e000 == 2 [pid = 1172] [id = {815da59b-d904-426d-a664-0004011b84da}]
[task 2018-03-02T01:13:21.957Z] 01:13:21     INFO - GECKO(1056) | ++DOMWINDOW == 3 (0x7fbcd0fd5000) [pid = 1172] [serial = 8] [outer = (nil)]
[task 2018-03-02T01:13:21.957Z] 01:13:21     INFO - checking window state
[task 2018-03-02T01:13:21.958Z] 01:13:21     INFO - GECKO(1056) | ++DOMWINDOW == 4 (0x7fbcd0fd5c00) [pid = 1172] [serial = 9] [outer = 0x7fbcd0fd5000]
[task 2018-03-02T01:13:21.959Z] 01:13:21     INFO - TEST-START | devtools/client/debugger/new/test/mochitest/browser_dbg-babel-stepping.js
[task 2018-03-02T01:13:21.979Z] 01:13:21     INFO - GECKO(1056) | ++DOCSHELL 0x7f1dfe651800 == 1 [pid = 1199] [id = {540eb989-6183-437b-9b65-b1186a26f555}]
[task 2018-03-02T01:13:21.979Z] 01:13:21     INFO - GECKO(1056) | ++DOMWINDOW == 1 (0x7f1dfe144000) [pid = 1199] [serial = 3] [outer = (nil)]
[task 2018-03-02T01:13:22.081Z] 01:13:22     INFO - GECKO(1056) | ++DOMWINDOW == 2 (0x7f1dfe145000) [pid = 1199] [serial = 4] [outer = 0x7f1dfe144000]
[task 2018-03-02T01:13:22.263Z] 01:13:22     INFO - GECKO(1056) | [Child 1199, Main Thread] WARNING: site security information will not be persisted: file /builds/worker/workspace/build/src/security/manager/ssl/nsSiteSecurityService.cpp, line 553
[task 2018-03-02T01:13:22.605Z] 01:13:22     INFO - GECKO(1056) | ++DOMWINDOW == 3 (0x7f1dfe149800) [pid = 1199] [serial = 5] [outer = 0x7f1dfe144000]
[task 2018-03-02T01:13:22.646Z] 01:13:22     INFO - GECKO(1056) | --DOCSHELL 0x7feb11c34800 == 4 [pid = 1056] [id = {3d323639-6659-4a4c-945d-1ae44b9a2544}]
[task 2018-03-02T01:13:26.553Z] 01:13:26     INFO - GECKO(1056) | ++DOCSHELL 0x7feb0fc20800 == 5 [pid = 1056] [id = {67e8431a-d44c-4323-8afa-7257b5c7bb2a}]
[task 2018-03-02T01:13:26.553Z] 01:13:26     INFO - GECKO(1056) | ++DOMWINDOW == 15 (0x7feb113be000) [pid = 1056] [serial = 30] [outer = (nil)]
[task 2018-03-02T01:13:26.554Z] 01:13:26     INFO - GECKO(1056) | ++DOMWINDOW == 16 (0x7feb113be400) [pid = 1056] [serial = 31] [outer = 0x7feb113be000]
[task 2018-03-02T01:13:26.691Z] 01:13:26     INFO - GECKO(1056) | ++DOMWINDOW == 17 (0x7feb113c3000) [pid = 1056] [serial = 32] [outer = 0x7feb113be000]
[task 2018-03-02T01:13:27.372Z] 01:13:27     INFO - GECKO(1056) | ++DOCSHELL 0x7feb11c34800 == 6 [pid = 1056] [id = {73dbb81e-8d2a-4c00-81bf-47b7f2c652fb}]
[task 2018-03-02T01:13:27.373Z] 01:13:27     INFO - GECKO(1056) | ++DOMWINDOW == 18 (0x7feb114dec00) [pid = 1056] [serial = 33] [outer = (nil)]
[task 2018-03-02T01:13:27.373Z] 01:13:27     INFO - GECKO(1056) | ++DOMWINDOW == 19 (0x7feb114e0c00) [pid = 1056] [serial = 34] [outer = 0x7feb114dec00]
[task 2018-03-02T01:13:29.004Z] 01:13:29     INFO - GECKO(1056) | --DOCSHELL 0x7fbcd0f12800 == 1 [pid = 1172] [id = {67c40332-97f3-4c97-abe0-2fcda915e5d2}]

:jlast could you please take a look?
Flags: needinfo?(jlaster)
Whiteboard: [stockwell needswork]
I think the increased time will be helpful, as some context the reason this test is failing is that the new babel-scopes and babel-stepping tests are 10X longer than the typical test. We can split them up too, but it is nicer to read it as one test.
Flags: needinfo?(jlaster)
There have been 38 failures in the last 7 days, all of them on windows-10-64-ccov.
Here is a recent failure log:
https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-central&job_id=167823795
and a snippet:
00:44:25     INFO -  385 INFO REMOVE_BREAKPOINT dispatched 1 time(s)
00:44:25     INFO -  386 INFO TEST-PASS | devtools/client/debugger/new/test/mochitest/browser_dbg-babel-scopes.js | Breakpoint reverted -
00:44:25     INFO -  387 INFO Resuming
00:44:25     INFO -  Buffered messages logged at 00:44:23
00:44:25     INFO -  388 INFO TEST-PASS | devtools/client/debugger/new/test/mochitest/browser_dbg-babel-scopes.js | Ran tests for webpack-modules-es6 at line 17 column 2 -
00:44:25     INFO -  389 INFO Leaving test bound
00:44:25     INFO -  Buffered messages logged at 00:44:24
00:44:25     INFO -  390 INFO Removing tab.
00:44:25     INFO -  391 INFO Waiting for event: 'TabClose' on [object XULElement].
00:44:25     INFO -  Buffered messages logged at 00:44:25
00:44:25     INFO -  392 INFO Got event: 'TabClose' on [object XULElement].
00:44:25     INFO -  393 INFO Tab removed and finished closing
00:44:25     INFO -  Buffered messages finished
00:44:25    ERROR -  394 INFO TEST-UNEXPECTED-FAIL | devtools/client/debugger/new/test/mochitest/browser_dbg-babel-scopes.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. -
00:44:25     INFO -  GECKO(4592) | MEMORY STAT | vsize 2432MB | vsizeMaxContiguous 132625807MB | residentFast 533MB | heapAllocated 142MB
00:44:25     INFO -  395 INFO TEST-OK | devtools/client/debugger/new/test/mochitest/browser_dbg-babel-scopes.js | took 432840ms
00:44:25     INFO -  GECKO(4592) | ++DOCSHELL 000001A28CE59000 == 2 [pid = 3604] [id = {be5e45d8-690c-4b2e-b1d1-b8fee8e4818d}]
00:44:25     INFO -  GECKO(4592) | ++DOMWINDOW == 3 (000001A29513A800) [pid = 3604] [serial = 8] [outer = 0000000000000000]
00:44:25     INFO -  GECKO(4592) | ++DOMWINDOW == 4 (000001A29513D400) [pid = 3604] [serial = 9] [outer = 000001A29513A800]
00:44:25     INFO -  GECKO(4592) | ++DOMWINDOW == 5 (000001A29513E400) [pid = 3604] [serial = 10] [outer = 000001A29513A800]
It's a slow test, lets skip it on the ccov platforms
the maxruntime for win10 coverage jobs were increased.
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → FIXED
Whiteboard: [stockwell unknown] → [stockwell fixed:other]
thanks for this- lets see if this is perma fail in a few days- I suspect it will be high frequency along with other *babel* tests.
Flags: needinfo?(jmaher)
There are 47 failures in the past 7 days.
Platforms: most of them occurred on windows10-64-ccov debug and we had some occurrences on linux64 also debug.
Recent log failure:
https://treeherder.mozilla.org/logviewer.html#?job_id=173124296&repo=mozilla-central&lineNumber=3192

Relevant part of the log:
16:20:41     INFO -  Buffered messages logged at 16:20:41
16:20:41     INFO -  110 INFO Got event: 'TabClose' on [object XULElement].
16:20:41     INFO -  111 INFO Tab removed and finished closing
16:20:41     INFO -  Buffered messages finished
16:20:41    ERROR -  112 INFO TEST-UNEXPECTED-FAIL | devtools/client/debugger/new/test/mochitest/browser_dbg-babel-scopes.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. -
16:20:41     INFO -  GECKO(2136) | MEMORY STAT | vsize 2475MB | vsizeMaxContiguous 131623595MB | residentFast 566MB | heapAllocated 184MB
16:20:41     INFO -  113 INFO TEST-OK | devtools/client/debugger/new/test/mochitest/browser_dbg-babel-scopes.js | took 435739ms
Flags: needinfo?(jmaher)
Arthur, could you disable this for win10 ccov ?

similar to the other skip for win10 ccov in the manifest:
https://searchfox.org/mozilla-central/source/devtools/client/debugger/new/test/mochitest/browser.ini#138
Flags: needinfo?(jmaher) → needinfo?(aiakab)
:jmaher, disabled on Windows 10 ccov. Should we also disable on Linux 64 ccov?
Assignee: nobody → aiakab
Flags: needinfo?(aiakab) → needinfo?(jmaher)
Attachment #8967681 - Flags: review?(jmaher)
Comment on attachment 8967681 [details] [diff] [review]
Disabled browser_dbg-babel-scopes.js on Windows 10 ccov

Review of attachment 8967681 [details] [diff] [review]:
-----------------------------------------------------------------

lets do linux ccov as well, I see yesterday it started failing there.
Attachment #8967681 - Flags: review?(jmaher)
Flags: needinfo?(jmaher)
Updated the patch for disabling ccov on Windows and Linux. :jmaher could you please take a look?
Flags: needinfo?(jmaher)
Attachment #8967722 - Flags: review?(jmaher)
Comment on attachment 8967722 [details] [diff] [review]
Disabled on ccov Windows and Linux

Review of attachment 8967722 [details] [diff] [review]:
-----------------------------------------------------------------

close, next time just ask for review, no need to needinfo

::: devtools/client/debugger/new/test/mochitest/browser.ini
@@ +144,4 @@
>  [browser_dbg-async-stepping.js]
>  [browser_dbg-babel-breakpoint-console.js]
>  [browser_dbg-babel-scopes.js]
> +skip-f = ccov && (os == "win" || os == "linux") # Bug 1441545

needs skip-if, small typo

also, ccov is only on win/linux, so we could do:
skip-if = ccov # Bug 1441545
Attachment #8967722 - Flags: review?(jmaher) → review-
Flags: needinfo?(jmaher)
Updated the patch.
Attachment #8967725 - Flags: review?(jmaher)
Comment on attachment 8967725 [details] [diff] [review]
Disabled on Linux and Windows ccov

Review of attachment 8967725 [details] [diff] [review]:
-----------------------------------------------------------------

one more iteration, the bug number is wrong in the comment.
Attachment #8967725 - Flags: review?(jmaher) → review-
Updated the patch
Attachment #8967736 - Flags: review?(jmaher)
Attachment #8967736 - Flags: review?(jmaher) → review+
Attachment #8967681 - Attachment is obsolete: true
Attachment #8967722 - Attachment is obsolete: true
Attachment #8967725 - Attachment is obsolete: true
Pushed by ryanvm@gmail.com:
https://hg.mozilla.org/integration/mozilla-inbound/rev/c44a1e373134
Disable browser_dbg-babel-scopes.js on Windows and Linux ccov. r=jmaher
Keywords: checkin-needed
https://hg.mozilla.org/mozilla-central/rev/c44a1e373134
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → FIXED
Target Milestone: --- → Firefox 61
Status: RESOLVED → REOPENED
Keywords: leave-open
Resolution: FIXED → ---
Whiteboard: [stockwell fixed:other] → [stockwell disabled]
Target Milestone: Firefox 61 → ---
Product: Firefox → DevTools
Status: REOPENED → RESOLVED
Closed: 2 years ago1 year ago
Resolution: --- → FIXED
You need to log in before you can comment on or make changes to this bug.