1620222 - Intermittent [TV] devtools/client/webconsole/test/browser/browser_console_eager_eval.js | Test timed out -

Status: RESOLVED FIXED

(DevTools :: Console, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: csabou [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=291804715&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/fY8YTlG3Trqla8wukkyKUA/runs/0/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://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/fY8YTlG3Trqla8wukkyKUA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

[task 2020-03-05T12:22:52.070Z] 12:22:52 INFO - TEST-START | devtools/client/webconsole/test/browser/browser_console_eager_eval.js
[task 2020-03-05T12:22:52.191Z] 12:22:52 INFO - GECKO(1219) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp03NOAC.mozrunner/runtests_leaks_tab_pid1460.log
[task 2020-03-05T12:22:52.260Z] 12:22:52 INFO - GECKO(1219) | [Parent 1219, Main Thread] WARNING: NS_ENSURE_TRUE(GetWrapper()) failed: file /builds/worker/workspace/build/src/dom/ipc/JSWindowActor.cpp, line 66
[task 2020-03-05T12:22:52.317Z] 12:22:52 INFO - GECKO(1219) | [Child 1460, Main Thread] WARNING: No CID found when attempting to map contract ID: file /builds/worker/workspace/build/src/xpcom/components/nsComponentManager.cpp, line 721
[task 2020-03-05T12:22:52.434Z] 12:22:52 INFO - GECKO(1219) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2020-03-05T12:22:52.640Z] 12:22:52 INFO - GECKO(1219) | [Child 1460, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/workspace/build/src/dom/ipc/ContentChild.cpp, line 1808
[task 2020-03-05T12:22:52.641Z] 12:22:52 INFO - GECKO(1219) | [Child 1460: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f6018e1e800 == 1 [pid = 1460] [id = {46d6caf4-f589-44ae-99ef-0e6478be4f31}]
[task 2020-03-05T12:22:52.642Z] 12:22:52 INFO - GECKO(1219) | [Child 1460: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f6034193520) [pid = 1460] [serial = 1] [outer = (nil)]
[task 2020-03-05T12:22:52.643Z] 12:22:52 INFO - GECKO(1219) | [Child 1460, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 845
[task 2020-03-05T12:22:52.644Z] 12:22:52 INFO - GECKO(1219) | [Child 1460: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f601911d400) [pid = 1460] [serial = 2] [outer = 0x7f6034193520]
[task 2020-03-05T12:22:52.692Z] 12:22:52 INFO - GECKO(1219) | [Child 1460: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f6019171400) [pid = 1460] [serial = 3] [outer = 0x7f6034193520]
[task 2020-03-05T12:22:53.058Z] 12:22:53 INFO - GECKO(1219) | [Parent 1219: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f6e14259800 == 7 [pid = 1219] [id = {69e91e87-fc3b-4058-91ad-444c27600dad}]
[task 2020-03-05T12:22:53.059Z] 12:22:53 INFO - GECKO(1219) | [Parent 1219: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 14 (0x7f6e1ef259f0) [pid = 1219] [serial = 14] [outer = (nil)]
[task 2020-03-05T12:22:53.060Z] 12:22:53 INFO - GECKO(1219) | [Parent 1219: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 15 (0x7f6e1abad800) [pid = 1219] [serial = 15] [outer = 0x7f6e1ef259f0]
[task 2020-03-05T12:22:53.076Z] 12:22:53 INFO - GECKO(1219) | [Parent 1219, Main Thread] WARNING: Attempting to get a displayport from a content with no primary frame!: file /builds/worker/workspace/build/src/layout/base/nsLayoutUtils.cpp, line 791
[task 2020-03-05T12:22:53.560Z] 12:22:53 INFO - GECKO(1219) | [Child 1276: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (0x7f8b1fc9f800) [pid = 1276] [serial = 4] [outer = (nil)] [url = about:blank]
[task 2020-03-05T12:22:53.560Z] 12:22:53 INFO - GECKO(1219) | [Child 1276: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (0x7f8b20594400) [pid = 1276] [serial = 10] [outer = (nil)] [url = about:blank]
[task 2020-03-05T12:22:53.560Z] 12:22:53 INFO - GECKO(1219) | [Child 1276: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (0x7f8b2058f400) [pid = 1276] [serial = 6] [outer = (nil)] [url = about:blank]
[task 2020-03-05T12:22:53.560Z] 12:22:53 INFO - GECKO(1219) | [Child 1276: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (0x7f8b1fc2b000) [pid = 1276] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-03-05T12:22:53.575Z] 12:22:53 INFO - GECKO(1219) | [Child 1276: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (0x7f8b20591800) [pid = 1276] [serial = 8] [outer = (nil)] [url = about:blank]
[task 2020-03-05T12:22:53.757Z] 12:22:53 INFO - GECKO(1219) | [Child 1276: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f8b205ce800 == 4 [pid = 1276] [id = {c9141a63-7277-4e13-b937-7e4e2ab051f7}] [url = moz-extension://4638f0af-f762-4259-b80b-82f33dd18f3b/_generated_background_page.html]
[task 2020-03-05T12:22:53.758Z] 12:22:53 INFO - GECKO(1219) | [Child 1276: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (0x7f8b3ae936f0) [pid = 1276] [serial = 3] [outer = (nil)] [url = moz-extension://4638f0af-f762-4259-b80b-82f33dd18f3b/_generated_background_page.html]
[task 2020-03-05T12:22:53.833Z] 12:22:53 INFO - GECKO(1219) | [Child 1299: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (0x7f7232697c00) [pid = 1299] [serial = 5] [outer = (nil)] [url = about:blank]
[task 2020-03-05T12:22:53.834Z] 12:22:53 INFO - GECKO(1219) | [Child 1299: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (0x7f723269d000) [pid = 1299] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-03-05T12:22:54.041Z] 12:22:54 INFO - GECKO(1219) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp03NOAC.mozrunner/runtests_leaks_tab_pid1492.log
[task 2020-03-05T12:22:54.467Z] 12:22:54 INFO - GECKO(1219) | [Child 1492, Main Thread] WARNING: No CID found when attempting to map contract ID: file /builds/worker/workspace/build/src/xpcom/components/nsComponentManager.cpp, line 721
[task 2020-03-05T12:22:54.993Z] 12:22:54 INFO - GECKO(1219) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2020-03-05T12:22:55.496Z] 12:22:55 INFO - GECKO(1219) | [Child 1492, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/workspace/build/src/dom/ipc/ContentChild.cpp, line 1808
[task 2020-03-05T12:22:55.496Z] 12:22:55 INFO - GECKO(1219) | [Child 1363: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fea993ea800 == 0 [pid = 1363] [id = {a9b4c17c-d225-431b-b3c8-4e12c86ba7c9}] [url = about:blank]
[task 2020-03-05T12:22:55.612Z] 12:22:55 INFO - GECKO(1219) | [Child 1299: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0x7f724d693520) [pid = 1299] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2020-03-05T12:22:55.750Z] 12:22:55 INFO - GECKO(1219) | [Child 1405: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fa688d21800 == 0 [pid = 1405] [id = {8ce1e31d-e149-484e-a953-a44a9efd85d0}] [url = data:text/html;charset=utf8,]
[task 2020-03-05T12:22:56.475Z] 12:22:56 INFO - GECKO(1219) | [Parent 1219, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, nullptr) failed with result 0x804B000A: file /builds/worker/workspace/build/src/extensions/permissions/nsPermission.cpp, line 45
[task 2020-03-05T12:22:56.475Z] 12:22:56 INFO - GECKO(1219) | [Parent 1219, Main Thread] WARNING: '!principal', file /builds/worker/workspace/build/src/netwerk/cookie/CookieJarSettings.cpp, line 182
[task 2020-03-05T12:22:56.475Z] 12:22:56 INFO - GECKO(1219) | [Parent 1219, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/toolkit/components/antitracking/StorageAccess.cpp, line 33
[task 2020-03-05T12:22:57.730Z] 12:22:57 INFO - GECKO(1219) | [Child 1276: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (0x7f8b1fc33000) [pid = 1276] [serial = 12] [outer = (nil)] [url = moz-extension://4638f0af-f762-4259-b80b-82f33dd18f3b/_generated_background_page.html]
[task 2020-03-05T12:22:58.019Z] 12:22:58 INFO - GECKO(1219) | [Child 1299: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (0x7f7232641c00) [pid = 1299] [serial = 3] [outer = (nil)] [url = about:blank]
[task 2020-03-05T12:22:58.019Z] 12:22:58 INFO - GECKO(1219) | [Child 1299: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7f7232f7e000) [pid = 1299] [serial = 7] [outer = (nil)] [url = about:blank]
[task 2020-03-05T12:22:59.868Z] 12:22:59 INFO - GECKO(1219) | [Child 1405: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7fa689966400) [pid = 1405] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-03-05T12:22:59.948Z] 12:22:59 INFO - GECKO(1219) | [Child 1405: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7fa6a4093520) [pid = 1405] [serial = 1] [outer = (nil)] [url = data:text/html;charset=utf8,]
[task 2020-03-05T12:23:01.976Z] 12:23:01 INFO - GECKO(1219) | [Parent 1219: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (0x7f6e256d5000) [pid = 1219] [serial = 8] [outer = (nil)] [url = about:blank]
[task 2020-03-05T12:23:02.595Z] 12:23:02 INFO - GECKO(1219) | [Parent 1219: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f6e17fbf800 == 6 [pid = 1219] [id = {c1b12383-b0e0-4146-a35a-c642dff6653a}] [url = chrome://devtools/content/webconsole/index.html]
[task 2020-03-05T12:23:02.596Z] 12:23:02 INFO - GECKO(1219) | [Parent 1219: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (0x7f6e1ef25820) [pid = 1219] [serial = 12] [outer = (nil)] [url = chrome://devtools/content/webconsole/index.html]
[task 2020-03-05T12:23:03.996Z] 12:23:03 INFO - GECKO(1219) | [Child 1405: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7fa68900b400) [pid = 1405] [serial = 3] [outer = (nil)] [url = data:text/html;charset=utf8,]
[task 2020-03-05T12:23:04.943Z] 12:23:04 INFO - GECKO(1219) | [Child 1460: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7f601911d400) [pid = 1460] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-03-05T12:23:05.180Z] 12:23:05 INFO - GECKO(1219) | [Child 1363: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7feab4393520) [pid = 1363] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2020-03-05T12:23:06.621Z] 12:23:06 INFO - GECKO(1219) | [Parent 1219: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (0x7f6e1d690c00) [pid = 1219] [serial = 13] [outer = (nil)] [url = about:blank]
[task 2020-03-05T12:23:09.251Z] 12:23:09 INFO - GECKO(1219) | [Child 1363: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7fea99b5dc00) [pid = 1363] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-03-05T12:24:22.220Z] 12:24:22 INFO - TEST-INFO | started process screentopng
[task 2020-03-05T12:24:22.526Z] 12:24:22 INFO - TEST-INFO | screentopng: exit 0
[task 2020-03-05T12:24:22.526Z] 12:24:22 INFO - Buffered messages logged at 12:22:52
[task 2020-03-05T12:24:22.527Z] 12:24:22 INFO - Entering test bound
[task 2020-03-05T12:24:22.527Z] 12:24:22 INFO - Adding a new tab with URL: data:text/html;charset=utf8,
[task 2020-03-05T12:24:22.528Z] 12:24:22 INFO - Tab added and finished loading
[task 2020-03-05T12:24:22.528Z] 12:24:22 INFO - Open the Browser Console
[task 2020-03-05T12:24:22.528Z] 12:24:22 INFO - Buffered messages logged at 12:22:53
[task 2020-03-05T12:24:22.529Z] 12:24:22 INFO - Console message: [JavaScript Warning: "The Web Console logging API (console.log, console.info, console.warn, console.error) has been disabled by a script on this page."]
[task 2020-03-05T12:24:22.530Z] 12:24:22 INFO - Buffered messages finished
[task 2020-03-05T12:24:22.530Z] 12:24:22 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_console_eager_eval.js | Test timed out -
[task 2020-03-05T12:24:22.531Z] 12:24:22 INFO - Removing tab.
[task 2020-03-05T12:24:22.532Z] 12:24:22 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2020-03-05T12:24:22.533Z] 12:24:22 INFO - Got event: 'TabClose' on [object XULElement].
[task 2020-03-05T12:24:22.533Z] 12:24:22 INFO - Tab removed and finished closing
[task 2020-03-05T12:24:22.534Z] 12:24:22 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_console_eager_eval.js | The main process DevToolsServer has no pending connection when the test ends -
[task 2020-03-05T12:24:22.534Z] 12:24:22 INFO - GECKO(1219) | console.warn: "Error while detaching the browsing context target front:" (new Error("Protocol error (noSuchActor): No such actor for ID: server2.conn0.child39/frameTarget1", "resource://devtools/shared/protocol/Front.js", 294))
[task 2020-03-05T12:24:22.535Z] 12:24:22 INFO - GECKO(1219) | console.warn: "IGNORED REDUX ACTION:" ({type:"MESSAGES_ADD", messages:[]})
[task 2020-03-05T12:24:22.661Z] 12:24:22 INFO - GECKO(1219) | console.warn: "IGNORED REDUX ACTION:" ({type:"AUTOCOMPLETE_CLEAR"})
[task 2020-03-05T12:24:22.769Z] 12:24:22 INFO - GECKO(1219) | MEMORY STAT | vsize 3080MB | residentFast 380MB | heapAllocated 123MB
[task 2020-03-05T12:24:22.776Z] 12:24:22 INFO - TEST-OK | devtools/client/webconsole/test/browser/browser_console_eager_eval.js | took 90705ms

Comment 1

[Logan Smyth [:loganfsmyth]]

Attachment: Bug 1620222 - Avoid redeclaration error in eager-eval test. r=nchevobbe!

Comment 2

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/757a618edfcb
Avoid redeclaration error in eager-eval test. r=nchevobbe

Comment 3

[Natalia Csoregi [:nataliaCs]]

https://hg.mozilla.org/mozilla-central/rev/757a618edfcb

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 8
• mozilla-central: 3

Platform breakdown:

• linux1804-64: 2
• macosx1014-64: 1
• windows7-32: 2
• windows10-64: 2
• linux1804-64-shippable: 1
• macosx1014-64-shippable: 1
• windows7-32-shippable: 1
• windows10-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1620222&startday=2020-03-02&endday=2020-03-08&tree=all