Closed Bug 1413485 Opened 7 years ago Closed 5 years ago

Intermittent devtools/startup/aboutdevtools/test/browser_aboutdevtools_reuse_existing.js | Uncaught exception - at chrome://mochitests/content/browser/devtools/shim/aboutdevtools/test/browser_aboutdevtools_reuse_existing.js:61

Categories

(DevTools :: General, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED WORKSFORME

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: archaeopteryx [at] coole-files.de

https://treeherder.mozilla.org/logviewer.html#?job_id=141101183&repo=autoland

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

Fails on second test run:

[task 2017-10-31T16:51:56.320Z] 16:51:56     INFO - :::
[task 2017-10-31T16:51:56.323Z] 16:51:56     INFO - ::: Running test verification step "1. Run each test 10 times in one browser."...
[task 2017-10-31T16:51:56.324Z] 16:51:56     INFO - :::

[task 2017-10-31T16:52:01.327Z] 16:52:01     INFO - *** Start BrowserChrome Test Results ***
[task 2017-10-31T16:52:01.369Z] 16:52:01     INFO - checking window state
[task 2017-10-31T16:52:01.433Z] 16:52:01     INFO - TEST-START | devtools/shim/aboutdevtools/test/browser_aboutdevtools_reuse_existing.js
[task 2017-10-31T16:52:02.716Z] 16:52:02     INFO - GECKO(1077) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2017-10-31T16:52:02.716Z] 16:52:02     INFO - GECKO(1077) | MEMORY STAT | vsize 2215MB | residentFast 306MB | heapAllocated 137MB
[task 2017-10-31T16:52:02.717Z] 16:52:02     INFO - TEST-OK | devtools/shim/aboutdevtools/test/browser_aboutdevtools_reuse_existing.js | took 1281ms
[task 2017-10-31T16:52:02.839Z] 16:52:02     INFO - checking window state
[task 2017-10-31T16:52:02.862Z] 16:52:02     INFO - TEST-START | devtools/shim/aboutdevtools/test/browser_aboutdevtools_reuse_existing.js
[task 2017-10-31T16:52:03.317Z] 16:52:03     INFO - TEST-INFO | started process screentopng
[task 2017-10-31T16:52:04.125Z] 16:52:04     INFO - TEST-INFO | screentopng: exit 0
[task 2017-10-31T16:52:04.126Z] 16:52:04     INFO - Buffered messages logged at 16:52:02
[task 2017-10-31T16:52:04.126Z] 16:52:04     INFO - Entering test bound 
[task 2017-10-31T16:52:04.127Z] 16:52:04     INFO - Add an about:blank tab
[task 2017-10-31T16:52:04.127Z] 16:52:04     INFO - Adding a new tab with URL: about:blank
[task 2017-10-31T16:52:04.128Z] 16:52:04     INFO - Buffered messages logged at 16:52:03
[task 2017-10-31T16:52:04.129Z] 16:52:04     INFO - Tab added and finished loading
[task 2017-10-31T16:52:04.129Z] 16:52:04     INFO - Trigger the toogle toolbox shortcut
[task 2017-10-31T16:52:04.130Z] 16:52:04     INFO - Wait for the about:devtools tab to be selected
[task 2017-10-31T16:52:04.131Z] 16:52:04     INFO - Select the about:blank tab again
[task 2017-10-31T16:52:04.131Z] 16:52:04     INFO - Trigger the toogle toolbox shortcut
[task 2017-10-31T16:52:04.132Z] 16:52:04     INFO - Wait for the about:devtools tab to be selected
[task 2017-10-31T16:52:04.133Z] 16:52:04     INFO - Buffered messages finished
[task 2017-10-31T16:52:04.134Z] 16:52:04     INFO - TEST-UNEXPECTED-FAIL | devtools/shim/aboutdevtools/test/browser_aboutdevtools_reuse_existing.js | Uncaught exception - at chrome://mochitests/content/browser/devtools/shim/aboutdevtools/test/browser_aboutdevtools_reuse_existing.js:61 - TypeError: browser.documentURI is null
[task 2017-10-31T16:52:04.134Z] 16:52:04     INFO - Stack trace:
[task 2017-10-31T16:52:04.135Z] 16:52:04     INFO -     isAboutDevtoolsTab@chrome://mochitests/content/browser/devtools/shim/aboutdevtools/test/browser_aboutdevtools_reuse_existing.js:61:7
[task 2017-10-31T16:52:04.138Z] 16:52:04     INFO -     @chrome://mochitests/content/browser/devtools/shim/aboutdevtools/test/browser_aboutdevtools_reuse_existing.js:35:27
[task 2017-10-31T16:52:04.138Z] 16:52:04     INFO -     Async*Tester_execTest/<@chrome://mochikit/content/browser-test.js:806:21
[task 2017-10-31T16:52:04.139Z] 16:52:04     INFO -     Tester_execTest@chrome://mochikit/content/browser-test.js:797:9
[task 2017-10-31T16:52:04.139Z] 16:52:04     INFO -     Tester.prototype.nextTest</<@chrome://mochikit/content/browser-test.js:692:9
[task 2017-10-31T16:52:04.140Z] 16:52:04     INFO -     SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:795:59
[task 2017-10-31T16:52:04.141Z] 16:52:04     INFO - Leaving test bound
:gbrown : This is a test-verify failure and it started happening only a day ago, but it already has 30 failures.
Could you please take a look?
Flags: needinfo?(gbrown)
Whiteboard: [stockwell needswork]
Test verification was triggered by bug 1410361, which added this test.

Test verification on this test will only happen when the test is updated, so there should be no more failures of this type (until the next time the test is modified).

I don't see any other bugs open for browser_aboutdevtools_reuse_existing.js, so this failure may not occur outside of test verification.

For these reasons, there is no pressing need to follow-up on the failure, but let's ping :jdescottes in case.

:jdescottes -- Test verification runs a test multiple times. In this case, it is failing on "step 1" of verification, which simply runs the test with --repeat. I can reproduce the failure locally with:

  mach mochitest devtools/shim/aboutdevtools/test/browser_aboutdevtools_reuse_existing.js --repeat 50
Blocks: 1410361
Flags: needinfo?(gbrown) → needinfo?(jdescottes)
See Also: → 1406204
I can't reproduce locally. Tried with --repeat 50 on latest central on OSX and Ubuntu. This might be an intermittent. I'm not sure yet how we can have a tab where browser.documentURI because at this stage of the test we have:
- 2 tabs of about:blank, which are loaded
- a new tab of about:devtools, but we waited on this tab by testing browser.documentURI on gBrowser.selectedTab

Maybe the tab gets a browser.documentURI (satisfying the waitUntil condition) and then loses it, failing the next test?

I made a try push with a small change to wait until the page is loaded. Let's see if it improves the results for test verify.
https://treeherder.mozilla.org/#/jobs?repo=try&revision=86c85f4322c436160eeac97108ca7ea7486f378d
Flags: needinfo?(jdescottes)
Thanks! I added test-verify to your try run. (You can always get it with "-u test-verify-e10s", if you like.)
Product: Firefox → DevTools
Summary: Perma test-verify devtools/shim/aboutdevtools/test/browser_aboutdevtools_reuse_existing.js | Uncaught exception - at chrome://mochitests/content/browser/devtools/shim/aboutdevtools/test/browser_aboutdevtools_reuse_existing.js:61 → Intermittent devtools/shim/aboutdevtools/test/browser_aboutdevtools_reuse_existing.js | Uncaught exception - at chrome://mochitests/content/browser/devtools/shim/aboutdevtools/test/browser_aboutdevtools_reuse_existing.js:61

There are 26 total failures in the last 7 days: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2019-07-10&endday=2019-07-17&tree=trunk&bug=1413485

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=256878126&repo=autoland&lineNumber=34099

08:28:31 INFO - TEST-START | devtools/startup/aboutdevtools/test/browser_aboutdevtools_reuse_existing.js
08:28:31 INFO - GECKO(1816) | ++DOCSHELL 0x127320000 == 2 [pid = 1819] [id = {9bee57e4-04fd-5545-82db-7a1d14d55691}]
08:28:31 INFO - GECKO(1816) | ++DOMWINDOW == 3 (0x12734c200) [pid = 1819] [serial = 3] [outer = 0x0]
08:28:31 INFO - GECKO(1816) | ++DOMWINDOW == 4 (0x1273c1800) [pid = 1819] [serial = 4] [outer = 0x12734c200]
08:28:31 INFO - GECKO(1816) | ++DOMWINDOW == 5 (0x11981ec00) [pid = 1819] [serial = 5] [outer = 0x12734c200]
08:28:31 INFO - GECKO(1816) | [Child 1819, Main Thread] WARNING: No active window: file /builds/worker/workspace/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662
08:28:32 INFO - TEST-INFO | started process screencapture
08:28:32 INFO - TEST-INFO | screencapture: exit 0
08:28:32 INFO - Buffered messages logged at 08:28:31
08:28:32 INFO - Entering test bound
08:28:32 INFO - Add an about:blank tab
08:28:32 INFO - Adding a new tab with URL: about:blank
08:28:32 INFO - Console message: [JavaScript Error: "uncaught exception: Object" {file: "resource://testing-common/PromiseTestUtils.jsm" line: 112}]
08:28:32 INFO - Buffered messages logged at 08:28:32
08:28:32 INFO - Tab added and finished loading
08:28:32 INFO - Trigger the toogle toolbox shortcut
08:28:32 INFO - Wait for the about:devtools tab to be selected
08:28:32 INFO - Buffered messages finished
08:28:32 INFO - TEST-UNEXPECTED-FAIL | devtools/startup/aboutdevtools/test/browser_aboutdevtools_reuse_existing.js | Uncaught exception - at chrome://mochitests/content/browser/devtools/startup/aboutdevtools/test/head.js:108 - TypeError: browser.documentURI is null
08:28:32 INFO - Stack trace:
08:28:32 INFO - isAboutDevtoolsTab@chrome://mochitests/content/browser/devtools/startup/aboutdevtools/test/head.js:108:20
08:28:32 INFO - @chrome://mochitests/content/browser/devtools/startup/aboutdevtools/test/browser_aboutdevtools_reuse_existing.js:21:25
08:28:32 INFO - waitUntil@chrome://mochitests/content/browser/devtools/startup/aboutdevtools/test/head.js:20:7
08:28:32 INFO - @chrome://mochitests/content/browser/devtools/startup/aboutdevtools/test/browser_aboutdevtools_reuse_existing.js:21:9
08:28:32 INFO - Async*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1346:34
08:28:32 INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1381:11
08:28:32 INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1209:14
08:28:32 INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:803:59
08:28:32 INFO - Leaving test bound
08:28:32 INFO - GECKO(1816) | ++DOCSHELL 0x11d4de000 == 11 [pid = 1816] [id = {c8a2fba0-71a1-8643-8e7d-ecee1af21a50}]
08:28:32 INFO - GECKO(1816) | ++DOMWINDOW == 26 (0x126071a60) [pid = 1816] [serial = 26] [outer = 0x0]
08:28:32 INFO - GECKO(1816) | ++DOMWINDOW == 27 (0x127d27000) [pid = 1816] [serial = 27] [outer = 0x126071a60]
08:28:32 INFO - GECKO(1816) | [Parent 1816, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/RangeBoundary.h, line 79
08:28:32 INFO - GECKO(1816) | [Parent 1816, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/RangeBoundary.h, line 79
08:28:32 INFO - GECKO(1816) | MEMORY STAT | vsize 7638MB | residentFast 385MB | heapAllocated 184MB

Whiteboard: [stockwell unknown] → [stockwell unknown][stockwell needswork:owner]
Summary: Intermittent devtools/shim/aboutdevtools/test/browser_aboutdevtools_reuse_existing.js | Uncaught exception - at chrome://mochitests/content/browser/devtools/shim/aboutdevtools/test/browser_aboutdevtools_reuse_existing.js:61 → Intermittent devtools/startup/aboutdevtools/test/browser_aboutdevtools_reuse_existing.js | Uncaught exception - at chrome://mochitests/content/browser/devtools/shim/aboutdevtools/test/browser_aboutdevtools_reuse_existing.js:61

no failures in >1 month

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → WORKSFORME
Whiteboard: [stockwell unknown][stockwell needswork:owner]
You need to log in before you can comment on or make changes to this bug.