1581995 - Intermittent TV devtools/client/debugger/test/mochitest/browser_dbg-log-events.js | Uncaught exception - waitFor - timed out after 500 tries.

Status: RESOLVED INCOMPLETE

(DevTools :: Debugger, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: dluca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=267137111&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/FodInvD1Sv-wZKnDQUJvMw/runs/1/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://queue.taskcluster.net/v1/task/FodInvD1Sv-wZKnDQUJvMw/runs/1/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

   INFO - TEST-INFO | started process screentopng
[task 2019-09-17T22:54:39.045Z] 22:54:39     INFO - TEST-INFO | screentopng: exit 0
[task 2019-09-17T22:54:39.045Z] 22:54:39     INFO - Buffered messages logged at 22:54:04
[task 2019-09-17T22:54:39.045Z] 22:54:39     INFO - Entering test bound 
[task 2019-09-17T22:54:39.046Z] 22:54:39     INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-event-breakpoints.html
[task 2019-09-17T22:54:39.047Z] 22:54:39     INFO - Buffered messages logged at 22:54:05
[task 2019-09-17T22:54:39.047Z] 22:54:39     INFO - Tab added and finished loading
[task 2019-09-17T22:54:39.048Z] 22:54:39     INFO - Opening the toolbox
[task 2019-09-17T22:54:39.048Z] 22:54:39     INFO - Buffered messages logged at 22:54:08
[task 2019-09-17T22:54:39.048Z] 22:54:39     INFO - Toolbox opened and focused
[task 2019-09-17T22:54:39.050Z] 22:54:39     INFO - Waiting on sources: event-breakpoints.js
[task 2019-09-17T22:54:39.050Z] 22:54:39     INFO - Finished waiting on sources: event-breakpoints.js
[task 2019-09-17T22:54:39.051Z] 22:54:39     INFO - click element #click-target in tab
[task 2019-09-17T22:54:39.053Z] 22:54:39     INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/NetUtil.jsm" line: 253}]
[task 2019-09-17T22:54:39.053Z] 22:54:39     INFO - Buffered messages finished
[task 2019-09-17T22:54:39.053Z] 22:54:39     INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-log-events.js | Uncaught exception - waitFor - timed out after 500 tries.
[task 2019-09-17T22:54:39.053Z] 22:54:39     INFO - Leaving test bound 
[task 2019-09-17T22:54:39.053Z] 22:54:39     INFO - GECKO(4757) | [Parent 4757, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/workspace/build/src/dom/events/DOMEventTargetHelper.cpp, line 318
[task 2019-09-17T22:54:39.053Z] 22:54:39     INFO - GECKO(4757) | [Parent 4757, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/workspace/build/src/dom/events/DOMEventTargetHelper.cpp, line 318
[task 2019-09-17T22:54:39.054Z] 22:54:39     INFO - GECKO(4757) | [Parent 4757, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/workspace/build/src/dom/events/DOMEventTargetHelper.cpp, line 318
[task 2019-09-17T22:54:39.054Z] 22:54:39     INFO - GECKO(4757) | [Parent 4757, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/workspace/build/src/dom/events/DOMEventTargetHelper.cpp, line 318
[task 2019-09-17T22:54:39.055Z] 22:54:39     INFO - Removing tab.
[task 2019-09-17T22:54:39.056Z] 22:54:39     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2019-09-17T22:54:39.056Z] 22:54:39     INFO - Got event: 'TabClose' on [object XULElement].
[task 2019-09-17T22:54:39.057Z] 22:54:39     INFO - Tab removed and finished closing
[task 2019-09-17T22:54:39.058Z] 22:54:39     INFO - TEST-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-log-events.js | Assertion count 1 is greater than expected range 0-0 assertions. - 
[task 2019-09-17T22:54:39.058Z] 22:54:39     INFO - GECKO(4757) | MEMORY STAT | vsize 3159MB | residentFast 470MB | heapAllocated 166MB
[task 2019-09-17T22:54:39.058Z] 22:54:39     INFO - TEST-OK | devtools/client/debugger/test/mochitest/browser_dbg-log-events.js | took 17139ms

Comment 1

[Dorel Luca [:dluca]]

This was introduced when Bug 1110276 landed here: https://hg.mozilla.org/integration/autoland/rev/05044e24d55dae370edd061bbf2bc4461a350067

jlast: Can you please take a look at this bug?

Comment 2

[Jason Laster [:jlast]]

Okay, happy to. Is it alright if this waits for tomorrow?

Comment 3

[Jason Laster [:jlast]]

The feature is preffed off

Comment 4

[Intermittent Failures Robot]

15 failures in 4350 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 8
• mozilla-central: 5
• mozilla-inbound: 2

Platform breakdown:

• macosx1014-64: 1
• linux64: 1
• macosx1014-64-shippable: 1
• linux64-shippable: 1
• windows7-32: 3
• windows10-64: 2
• windows10-64-shippable: 3
• windows7-32-shippable: 3

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

Comment 5

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.