Closed Bug 1383582 Opened 7 years ago Closed 5 years ago

Intermittent devtools/client/debugger/new/test/mochitest/browser_dbg-tabs.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)

defect
Not set
normal

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

09:05:05     INFO - Waiting on sources: simple1, simple2
09:05:05     INFO - TEST-PASS | devtools/client/debugger/new/test/mochitest/browser_dbg-tabs.js | undefined assertion name - 
09:05:05     INFO - Leaving test bound 
09:05:05     INFO - Console message: [JavaScript Error: "TypeError: aBrowser is null" {file: "resource:///modules/ZoomUI.jsm" line: 62}]
09:05:05     INFO - Removing tab.
09:05:05     INFO - Waiting for event: 'TabClose' on [object XULElement].
09:05:05     INFO - Got event: 'TabClose' on [object XULElement].
09:05:05     INFO - Tab removed and finished closing
09:05:05     INFO - Console message: [JavaScript Error: "TypeError: aBrowser is null" {file: "resource:///modules/ZoomUI.jsm" line: 62}]
09:05:05     INFO - Console message: [JavaScript Warning: "Loading failed for the <script> with source “http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/simple1.js”." {file: "http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/doc-scripts.html" line: 11}]
09:05:05     INFO - Removing tab.
09:05:05     INFO - Waiting for event: 'TabClose' on [object XULElement].
09:05:05     INFO - Got event: 'TabClose' on [object XULElement].
09:05:05     INFO - Tab removed and finished closing
09:05:05     INFO - Buffered messages finished
09:05:05     INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/new/test/mochitest/browser_dbg-tabs.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. -
https://wiki.mozilla.org/Bugmasters#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: REOPENED → RESOLVED
Closed: 7 years ago6 years ago
Resolution: --- → INCOMPLETE
Product: Firefox → DevTools
Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=190880256&repo=autoland&lineNumber=3634

06:49:17     INFO - TEST-PASS | devtools/client/debugger/new/test/mochitest/browser_dbg-preview-source-maps.js | Preview.updating - 
06:49:17     INFO - TEST-PASS | devtools/client/debugger/new/test/mochitest/browser_dbg-preview-source-maps.js | Preview.expression - 
06:49:17     INFO - Test previewing in the generated location
06:49:17     INFO - Buffered messages logged at 06:48:56
06:49:17     INFO - Waiting for state change: selected source
06:49:17     INFO - Buffered messages logged at 06:49:01
06:49:17     INFO - Finished waiting for state change: selected source
06:49:17     INFO - Buffered messages logged at 06:49:02
06:49:17     INFO - Hovering at 70, 10
06:49:17     INFO - Buffered messages logged at 06:49:03
06:49:17     INFO - Hovering at 70, 10
06:49:17     INFO - Buffered messages logged at 06:49:04
06:49:17     INFO - TEST-PASS | devtools/client/debugger/new/test/mochitest/browser_dbg-preview-source-maps.js | Preview text shown to user - 
06:49:17     INFO - TEST-PASS | devtools/client/debugger/new/test/mochitest/browser_dbg-preview-source-maps.js | Preview.updating - 
06:49:17     INFO - TEST-PASS | devtools/client/debugger/new/test/mochitest/browser_dbg-preview-source-maps.js | Preview.expression - 
06:49:17     INFO - Test that you can not preview in another original file
06:49:17     INFO - Buffered messages logged at 06:49:09
06:49:17     INFO - Waiting for state change: selected source
06:49:17     INFO - Buffered messages logged at 06:49:10
06:49:17     INFO - Finished waiting for state change: selected source
06:49:17     INFO - Hovering at 2, 16
06:49:17     INFO - Buffered messages logged at 06:49:11
06:49:17     INFO - TEST-PASS | devtools/client/debugger/new/test/mochitest/browser_dbg-preview-source-maps.js | Tooltip should not exist - 
06:49:17     INFO - Leaving test bound 
06:49:17     INFO - Buffered messages logged at 06:49:13
06:49:17     INFO - Removing tab.
06:49:17     INFO - Waiting for event: 'TabClose' on [object XULElement].
06:49:17     INFO - Got event: 'TabClose' on [object XULElement].
06:49:17     INFO - Tab removed and finished closing
06:49:17     INFO - Buffered messages finished
06:49:17     INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/new/test/mochitest/browser_dbg-preview-source-maps.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. - 
06:49:17     INFO - GECKO(760) | MEMORY STAT | vsize 4585MB | residentFast 549MB | heapAllocated 167MB
06:49:17     INFO - TEST-OK | devtools/client/debugger/new/test/mochitest/browser_dbg-preview-source-maps.js | took 49301ms
06:49:17     INFO - checking window state
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: REOPENED → RESOLVED
Closed: 6 years ago6 years ago
Resolution: --- → INCOMPLETE
Fail reappeared on autoland.

Log link: https://treeherder.mozilla.org/logviewer.html#?job_id=207440506&repo=autoland&lineNumber=12879
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
This bug has failed 31 times in the last 7 days. Only occurs on windows10-64-ccov debug build type.

Log:
INFO - TEST-PASS | devtools/client/debugger/new/test/mochitest/browser_dbg-tabs.js | undefined assertion name - 
12:34:25     INFO - Waiting for NAVIGATE to dispatch 1 time(s)
12:34:25     INFO - Buffered messages logged at 12:33:46
12:34:25     INFO - NAVIGATE dispatched 1 time(s)
12:34:25     INFO - Waiting on sources: simple1, simple2
12:34:25     INFO - Waiting for state change: source simple1 exists
12:34:25     INFO - Waiting for state change: source simple2 exists
12:34:25     INFO - Buffered messages logged at 12:33:48
12:34:25     INFO - Finished waiting for state change: source simple1 exists
12:34:25     INFO - Finished waiting for state change: source simple2 exists
12:34:25     INFO - Finished waiting on sources: simple1, simple2
12:34:25     INFO - Buffered messages logged at 12:33:49
12:34:25     INFO - TEST-PASS | devtools/client/debugger/new/test/mochitest/browser_dbg-tabs.js | undefined assertion name - 
12:34:25     INFO - Leaving test bound 
12:34:25     INFO - Buffered messages logged at 12:34:03
12:34:25     INFO - Removing tab.
12:34:25     INFO - Waiting for event: 'TabClose' on [object XULElement].
12:34:25     INFO - Got event: 'TabClose' on [object XULElement].
12:34:25     INFO - Tab removed and finished closing
12:34:25     INFO - Buffered messages logged at 12:34:14
12:34:25     INFO - Removing tab.
12:34:25     INFO - Waiting for event: 'TabClose' on [object XULElement].
12:34:25     INFO - Got event: 'TabClose' on [object XULElement].
12:34:25     INFO - Tab removed and finished closing
12:34:25     INFO - Buffered messages logged at 12:34:24
12:34:25     INFO - Removing tab.
12:34:25     INFO - Waiting for event: 'TabClose' on [object XULElement].
12:34:25     INFO - Buffered messages logged at 12:34:25
12:34:25     INFO - Got event: 'TabClose' on [object XULElement].
12:34:25     INFO - Tab removed and finished closing
12:34:25     INFO - Buffered messages finished
12:34:25     INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/new/test/mochitest/browser_dbg-tabs.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. - 
12:34:25     INFO - GECKO(764) | MEMORY STAT | vsize 2313MB | vsizeMaxContiguous 131817755MB | residentFast 505MB | heapAllocated 128MB
12:34:25     INFO - TEST-OK | devtools/client/debugger/new/test/mochitest/browser_dbg-tabs.js | took 133888ms
12:34:25     INFO - GECKO(764) | ++DOCSHELL 000001BCE2F6A000 == 1 [pid = 5280] [id = {66740639-08d1-4f92-8220-639b790112c2}]
12:34:25     INFO - GECKO(764) | ++DOMWINDOW == 2 (000001BCE2F2DA00) [pid = 5280] [serial = 77] [outer = 0000000000000000]
12:34:25     INFO - GECKO(764) | ++DOMWINDOW == 3 (000001BCE2FD0000) [pid = 5280] [serial = 78] [outer = 000001BCE2F2DA00]
12:34:25     INFO - checking window state
12:34:25     INFO - TEST-START | devtools/client/debugger/new/test/mochitest/browser_dbg-toggling-tools.js
12:34:25     INFO - GECKO(764) | ++DOCSHELL 000001D3A4174800 == 2 [pid = 1320] [id = {bd212425-245c-4b1c-a796-dccc5295b6be}]
12:34:25     INFO - GECKO(764) | ++DOMWINDOW == 3 (000001D3AA7A4A00) [pid = 1320] [serial = 157] [outer = 0000000000000000]
12:34:26     INFO - GECKO(764) | ++DOMWINDOW == 4 (000001D3AA3AF400) [pid = 1320] [serial = 158] [outer = 000001D3AA7A4A00]
12:34:26     INFO - GECKO(764) | ++DOMWINDOW == 5 (000001D3AA7B9800) [pid = 1320] [serial = 159] [outer = 000001D3AA7A4A00]
12:34:26     INFO - GECKO(764) | ++DOCSHELL 00000230BC126800 == 8 [pid = 7648] [id = {cf80ff7d-ef74-4d71-b2a6-bf216d7a5240}]
12:34:26     INFO - GECKO(764) | ++DOMWINDOW == 27 (00000230BCF31400) [pid = 7648] [serial = 389] [outer = 0000000000000000]
12:34:26     INFO - GECKO(764) | ++DOMWINDOW == 28 (00000230BD896400) [pid = 7648] [serial = 390] [outer = 00000230BCF31400]
12:34:27     INFO - GECKO(764) | ++DOMWINDOW == 29 (00000230BD8A2800) [pid = 7648] [serial = 391] [outer = 00000230BCF31400]
12:34:28     INFO - GECKO(764) | ++DOCSHELL 00000230BC126000 == 9 [pid = 7648] [id = {faa049a7-4b1d-4008-970c-3fbcb50f6527}]
12:34:28     INFO - GECKO(764) | ++DOMWINDOW == 30 (00000230BCF31A00) [pid = 7648] [serial = 392] [outer = 0000000000000000]
12:34:28     INFO - GECKO(764) | ++DOMWINDOW == 31 (00000230BDCA9800) [pid = 7648] [serial = 393] [outer = 00000230BCF31A00]
12:34:29     INFO - GECKO(764) | --DOMWINDOW == 2 (000001BCE91DFC00) [pid = 5280] [serial = 76] [outer = 0000000000000000] [url = http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/doc-scripts.html]
12:34:31     INFO - GECKO(764) | --DOMWINDOW == 4 (000001D3AA7A4000) [pid = 1320] [serial = 152] [outer = 0000000000000000] [url = http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/doc-scripts.html]
12:34:31     INFO - GECKO(764) | --DOCSHELL 000001D3AA3D3800 == 1 [pid = 1320] [id = {a6ca3e92-4744-4269-887f-0eba919acd86}]
12:34:32     INFO - GECKO(764) | [ACTION] ADD_SOURCES  - {"type":"ADD_SOURCES","sources":[{"id":"server1.conn58.child1/source24","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/doc-scripts.html","relativeUrl":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/doc-scripts.html","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":null,"isBlackBoxed":false,"loadedState":"unloaded"}]}
12:34:32     INFO - GECKO(764) | [ACTION] ADD_SOURCES  - {"type":"ADD_SOURCES","sources":[{"id":"server1.conn58.child1/source25","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/simple3.js","relativeUrl":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/simple3.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":null,"isBlackBoxed":false,"loadedState":"unloaded"}]}
12:34:33     INFO - GECKO(764) | [ACTION] ADD_SOURCES  - {"type":"ADD_SOURCES","sources":[{"id":"server1.conn58.child1/source26","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/long.js","relativeUrl":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/long.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":null,"isBlackBoxed":false,"loadedState":"unloaded"}]}
12:34:33     INFO - GECKO(764) | [ACTION] ADD_SOURCES  - {"type":"ADD_SOURCES","sources":[{"id":"server1.conn58.child1/source27","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/simple2.js","relativeUrl":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/simple2.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":null,"isBlackBoxed":false,"loadedState":"unloaded"},{"id":"server1.conn58.child1/source28","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/simple1.js","relativeUrl":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/simple1.js","isPrettyPrinted":false,"isWasm":false,"sourceMapURL":null,"isBlackBoxed":false,"loadedState":"unloaded"}]}
12:34:33     INFO - GECKO(764) | [ACTION] SET_WORKERS  - {"type":"SET_WORKERS","workers":[]}
12:34:33     INFO - GECKO(764) | [ACTION] CONNECT  - {"type":"CONNECT","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/doc-scripts.html","canRewind":false}
12:34:33     INFO - GECKO(764) | [ACTION] PAUSE_ON_EXCEPTIONS [start] - {"type":"PAUSE_ON_EXCEPTIONS","shouldPauseOnExceptions":false,"shouldPauseOnCaughtExceptions":true,"seqId":"1","status":"start"}
12:34:33     INFO - GECKO(764) | [ACTION] PAUSE_ON_EXCEPTIONS [done] - {"type":"PAUSE_ON_EXCEPTIONS","shouldPauseOnExceptions":false,"shouldPauseOnCaughtExceptions":true,"seqId":"1","status":"done","value":{"from":"server1.conn58.child1/context22","type":"paused","actor":"server1.conn58.child1/pause29","poppedFrames":[],"why":{"type":"interrupted"}}}
12:34:34     INFO - GECKO(764) | --DOCSHELL 0000021BD5C73000 == 0 [pid = 6232] [id = {bc909d2c-95cb-4c60-bf3d-9fd652e81157}]
12:34:35     INFO - GECKO(764) | [ACTION] SET_EXPANDED_STATE  - {"type":"SET_EXPANDED_STATE","expanded":{}}
12:34:35     INFO - GECKO(764) | [ACTION] SET_ORIENTATION  - {"type":"SET_ORIENTATION","orientation":"horizontal"}
12:34:35     INFO - GECKO(764) | --DOMWINDOW == 3 (000001D3AA3AF400) [pid = 1320] [serial = 158] [outer = 0000000000000000] [url = about:blank]
12:34:35     INFO - GECKO(764) | --DOMWINDOW == 2 (000001D3AA7ABC00) [pid = 1320] [serial = 156] [outer = 0000000000000000] [url = http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/doc-scripts.html]
12:34:36     INFO - GECKO(764) | [ACTION] ADD_TAB  - {"type":"ADD_TAB","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/long.js","isOriginal":false,"sourceId":"server1.conn58.child1/source26"}
12:34:37     INFO - GECKO(764) | [ACTION] SET_SELECTED_LOCATION  - {"type":"SET_SELECTED_LOCATION","source":{"id":"server1.conn58.child1/source26","url":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/long.js","sourceMapURL":null,"isBlackBoxed":false,"isPrettyPrinted":false,"isWasm":false,"contentType":"","loadedState":"unloaded","relativeUrl":"http://example.com/browser/devtools/client/debugger/new/test/mochitest/examples/long.js"},"location":{"sourceId":"server1.conn58.child1/source26"}}
12:34:38     INFO - GECKO(764) | [Parent 7648, Main Thread] WARNING: Please do not use mouseenter/leave events in chrome. They are slower than mouseover/out!: '!nsContentUtils::IsChromeDoc(d)', file z:/build/build/src/dom/events/EventListenerManager.cpp, line 379
12:34:38     INFO - GECKO(764) | [Parent 7648, Main Thread] WARNING: Please do not use mouseenter/leave events in chrome. They are slower than mouseover/out!: '!nsContentUtils::IsChromeDoc(d)', file z:/build/build/src/dom/events/EventListenerManager.cpp, line 379
12:34:39     INFO - GECKO(764) | [ACTION] LOAD_SOURCE_TEXT [start] - {"type":"LOAD_SOURCE_TEXT","sourceId":"server1.conn58.child1/source26","seqId":"2","status":"start"}
12:34:39     INFO - GECKO(764) | [ACTION] LOAD_SOURCE_TEXT [done] - {"type":"LOAD_SOURCE_TEXT","sourceId":"server1.conn58.child1/source26","seqId":"2","status":"done","value":{"id":"server1.conn58.child1/source26","text":"","contentType":"text/javascript"}}
12:34:41     INFO - GECKO(764) | [ACTION] SET_SYMBOLS [start] - {}
12:34:41     INFO - GECKO(764) | [ACTION] OUT_OF_SCOPE_LOCATIONS  - {}
12:34:41     INFO - GECKO(764) | [ACTION] IN_SCOPE_LINES  - {}
12:34:42     INFO - GECKO(764) | [ACTION] SET_SYMBOLS [done] - {}
12:34:42     INFO - GECKO(764) | [ACTION] SET_PAUSE_POINTS  - {}
12:34:43     INFO - GECKO(764) | [ACTION] SET_SOURCE_METADATA  - {"type":"SET_SOURCE_METADATA","sourceId":"server1.conn58.child1/source26","sourceMetaData":{}}
12:34:43     INFO - GECKO(764) | ++DOMWINDOW == 32 (00000230BDCABC00) [pid = 7648] [serial = 394] [outer = 00000230BCF31400]
12:34:44     INFO - GECKO(764) | --DOMWINDOW == 1 (0000021BDCC1FA00) [pid = 6232] [serial = 86] [outer = 0000000000000000] [url = about:blank]
12:34:48     INFO - GECKO(764) | --DOMWINDOW == 0 (0000021BD5CC4800) [pid = 6232] [serial = 87] [outer = 0000000000000000] [url = about:blank]
12:34:50     INFO - GECKO(764) | --DOMWINDOW == 31 (00000230C21D1000) [pid = 7648] [serial = 380] [outer = 0000000000000000] [url = about:blank]
12:34:50     INFO - GECKO(764) | --DOMWINDOW == 30 (00000230C21D0400) [pid = 7648] [serial = 378] [outer = 0000000000000000] [url = about:devtools-toolbox]
12:34:50     INFO - GECKO(764) | --DOMWINDOW == 29 (00000230BD7A7000) [pid = 7648] [serial = 387] [outer = 0000000000000000] [url = about:blank]
12:34:50     INFO - GECKO(764) | --DOMWINDOW == 28 (00000230BEECBC00) [pid = 7648] [serial = 377] [outer = 0000000000000000] [url = about:blank]
12:34:54     INFO - GECKO(764) | [Parent 7648, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file z:/build/build/src/obj-firefox/dist/include\mozilla/DOMEventTargetHelper.h, line 145
12:34:54     INFO - GECKO(764) | [Parent 7648, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file z:/build/build/src/obj-firefox/dist/include\mozilla/DOMEventTargetHelper.h, line 145
12:34:54     INFO - GECKO(764) | [Parent 7648, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file z:/build/build/src/obj-firefox/dist/include\mozilla/DOMEventTargetHelper.h, line 145
12:34:54     INFO - GECKO(764) | [Parent 7648, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file z:/build/build/src/obj-firefox/dist/include\mozilla/DOMEventTargetHelper.h, line 145
12:34:55     INFO - GECKO(764) | MEMORY STAT | vsize 2296MB | vsizeMaxContiguous 131817755MB | residentFast 509MB | heapAllocated 140MB
12:34:55     INFO - TEST-OK | devtools/client/debugger/new/test/mochitest/browser_dbg-toggling-tools.js | took 29188ms

jlast: Can you take a look at this bug?
Flags: needinfo?(jlaster)
Adding david to this test as he was touching tabs lately.
Flags: needinfo?(jlaster) → needinfo?(dwalsh)
I split the tests up in debugger.html; this will hit release early next week.
Flags: needinfo?(dwalsh)
Status: REOPENED → RESOLVED
Closed: 6 years ago5 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.