1490553 - Intermittent devtools/client/webconsole/test/mochitest/browser_webconsole_split.js | Test timed out -

Status: RESOLVED INCOMPLETE

(DevTools :: Console, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: btara [at] mozilla.com

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

https://queue.taskcluster.net/v1/task/OM_qh2DkSbOcLD1SJ28t-A/runs/1/artifacts/public/logs/live_backing.log

[task 2018-09-12T00:22:27.086Z] 00:22:27     INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_split.js | Splitter is hidden after toggling - 
[task 2018-09-12T00:22:27.086Z] 00:22:27     INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_split.js | Deck has a height > 0 after toggling - 
[task 2018-09-12T00:22:27.088Z] 00:22:27     INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_split.js | Web console is collapsed after toggling - 
[task 2018-09-12T00:22:27.088Z] 00:22:27     INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_split.js | The console panel is not the current tool - 
[task 2018-09-12T00:22:27.088Z] 00:22:27     INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_split.js | The menu item indicates the console is not split - 
[task 2018-09-12T00:22:27.089Z] 00:22:27     INFO - Buffered messages logged at 00:21:31
[task 2018-09-12T00:22:27.089Z] 00:22:27     INFO - Adding a new tab with URL: data:text/html;charset=utf-8,Web Console test for splitting
[task 2018-09-12T00:22:27.090Z] 00:22:27     INFO - Buffered messages logged at 00:21:37
[task 2018-09-12T00:22:27.091Z] 00:22:27     INFO - Tab added and finished loading
[task 2018-09-12T00:22:27.091Z] 00:22:27     INFO - About to check console loads even when non-webconsole panel is open
[task 2018-09-12T00:22:27.092Z] 00:22:27     INFO - Buffered messages finished
[task 2018-09-12T00:22:27.092Z] 00:22:27     INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/mochitest/browser_webconsole_split.js | Test timed out - 
[task 2018-09-12T00:22:35.149Z] 00:22:35     INFO - GECKO(3552) | WebConsolePanel open failed. undefined: iframe is null, can't access property "contentWindow" of it
[task 2018-09-12T00:22:35.159Z] 00:22:35     INFO - GECKO(3552) | console.error: "WebConsolePanel open failed. undefined: iframe is null, can't access property \"contentWindow\" of it" (new TypeError("iframe is null, can't access property \"contentWindow\" of it", "resource://devtools/shared/base-loader.js -> resource://devtools/client/webconsole/panel.js", 47))
[task 2018-09-12T00:22:35.514Z] 00:22:35     INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-09-12T00:22:35.523Z] 00:22:35     INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/mochitest/browser_webconsole_split.js | Uncaught exception received from previously timed out test - at resource://devtools/shared/base-loader.js -> resource://devtools/client/webconsole/panel.js:32 - TypeError: this.hud is null, can't access property "jsterm" of it
[task 2018-09-12T00:22:35.523Z] 00:22:35     INFO - Stack trace:
[task 2018-09-12T00:22:35.523Z] 00:22:35     INFO - focusInput@resource://devtools/shared/base-loader.js -> resource://devtools/client/webconsole/panel.js:32:5
[task 2018-09-12T00:22:35.523Z] 00:22:35     INFO - focusConsoleInput@resource://devtools/shared/base-loader.js -> resource://devtools/client/framework/toolbox.js:2009:7
[task 2018-09-12T00:22:35.523Z] 00:22:35     INFO - openSplitConsole/<@resource://devtools/shared/base-loader.js -> resource://devtools/client/framework/toolbox.js:2059:7
[task 2018-09-12T00:22:35.523Z] 00:22:35     INFO - promise callback*openSplitConsole@resource://devtools/shared/base-loader.js -> resource://devtools/client/framework/toolbox.js:2051:40
[task 2018-09-12T00:22:35.523Z] 00:22:35     INFO - toggleSplitConsole@resource://devtools/shared/base-loader.js -> resource://devtools/client/framework/toolbox.js:2100:14
[task 2018-09-12T00:22:35.523Z] 00:22:35     INFO - testConsoleLoadOnDifferentPanel@chrome://mochitests/content/browser/devtools/client/webconsole/test/mochitest/browser_webconsole_split.js:57:11
[task 2018-09-12T00:22:35.523Z] 00:22:35     INFO - async*performTests@chrome://mochitests/content/browser/devtools/client/webconsole/test/mochitest/browser_webconsole_split.js:29:9
[task 2018-09-12T00:22:35.523Z] 00:22:35     INFO - async*@chrome://mochitests/content/browser/devtools/client/webconsole/test/mochitest/browser_webconsole_split.js:23:9
[task 2018-09-12T00:22:35.523Z] 00:22:35     INFO - Async*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1102:34
[task 2018-09-12T00:22:35.524Z] 00:22:35     INFO - async*Tester_execTest@chrome://mochikit/content/browser-test.js:1093:16
[task 2018-09-12T00:22:35.524Z] 00:22:35     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:995:9
[task 2018-09-12T00:22:35.524Z] 00:22:35     INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:795:59
[task 2018-09-12T00:22:41.255Z] 00:22:41     INFO - Removing tab.
[task 2018-09-12T00:22:41.262Z] 00:22:41     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2018-09-12T00:22:43.442Z] 00:22:43     INFO - Got event: 'TabClose' on [object XULElement].
[task 2018-09-12T00:22:45.059Z] 00:22:45     INFO - Tab removed and finished closing
[task 2018-09-12T00:22:47.685Z] 00:22:47     INFO - GECKO(3552) | console.error: (new TypeError("event is undefined, can't access property \"extra\" of it", "resource://devtools/shared/base-loader.js -> resource://devtools/client/shared/telemetry.js", 628))
[task 2018-09-12T00:22:49.994Z] 00:22:49     INFO - Removing tab.
[task 2018-09-12T00:22:49.998Z] 00:22:49     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2018-09-12T00:22:50.472Z] 00:22:50     INFO - Got event: 'TabClose' on [object XULElement].
[task 2018-09-12T00:22:50.891Z] 00:22:50     INFO - Tab removed and finished closing
[task 2018-09-12T00:22:52.082Z] 00:22:52     INFO - GECKO(3552) | MEMORY STAT | vsize 20983186MB | residentFast 3852MB
[task 2018-09-12T00:22:52.084Z] 00:22:52     INFO - TEST-OK | devtools/client/webconsole/test/mochitest/browser_webconsole_split.js | took 504542ms
[task 2018-09-12T00:22:52.923Z] 00:22:52     INFO - checking window state
[task 2018-09-12T00:22:53.830Z] 00:22:53     INFO - TEST-START | devtools/client/webconsole/test/mochitest/browser_webconsole_split_close_button.js
[task 2018-09-12T00:23:28.847Z] 00:23:28     INFO - GECKO(3552) | MEMORY STAT | vsize 20983179MB | residentFast 3839MB
[task 2018-09-12T00:23:28.849Z] 00:23:28     INFO - TEST-OK | devtools/client/webconsole/test/mochitest/browser_webconsole_split_close_button.js | took 35033ms
[task 2018-09-12T00:23:29.146Z] 00:23:29     INFO - checking window state

Comment 1

[Intermittent Failures Robot]

1 failures in 2684 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* mozilla-central: 1

Platform breakdown:
* linux64: 1

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

Comment 2

[Intermittent Failures Robot]

1 failures in 2902 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* try: 1

Platform breakdown:
* linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1490553&startday=2018-09-17&endday=2018-09-23&tree=all

Comment 3

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Comment 4

[Narcis Beleuzu [:NarcisB]]

Fail reappeared on autoland.
Log link: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=223933603&repo=autoland&lineNumber=3172

Log snippet:

01:45:36 INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_split.js | The menu item indicates the console is not split -
01:45:36 INFO - Console message: [JavaScript Warning: "Successfully compiled asm.js code (total compilation time 79ms; unable to cache asm.js in synchronous scripts; try loading asm.js via <script async> or createElement('script'))" {file: "resource://devtools/client/shared/demangle.js" line: 0}]
01:45:36 INFO - Buffered messages logged at 01:43:21
01:45:36 INFO - Longer timeout required, waiting longer... Remaining timeouts: 3
01:45:36 INFO - Buffered messages logged at 01:44:06
01:45:36 INFO - Longer timeout required, waiting longer... Remaining timeouts: 2
01:45:36 INFO - Buffered messages logged at 01:44:25
01:45:36 INFO - Console message: [JavaScript Error: "Unknown collection "main/tippytop"" {file: "resource://services-settings/RemoteSettingsClient.jsm" line: 261}]
01:45:36 INFO - sync@resource://services-settings/RemoteSettingsClient.jsm:261:13
01:45:36 INFO - asyncget@resource://services-settings/RemoteSettingsClient.jsm:230:17
01:45:36 INFO - asyncgetSite@resource://activity-stream/lib/FaviconFeed.jsm:156:25
01:45:36 INFO - asyncfetchIcon@resource://activity-stream/lib/FaviconFeed.jsm:130:24
01:45:36 INFO - asynconAction@resource://activity-stream/lib/FaviconFeed.jsm:180:9
01:45:36 INFO - _middleware/</<@resource://activity-stream/lib/Store.jsm:51:11
01:45:36 INFO - Store/this[method]@resource://activity-stream/lib/Store.jsm:29:54
01:45:36 INFO - _requestRichIcon@resource://activity-stream/lib/TopSitesFeed.jsm:460:5
01:45:36 INFO - _fetchIcon@resource://activity-stream/lib/TopSitesFeed.jsm:424:5
01:45:36 INFO - asyncgetLinksWithDefaults@resource://activity-stream/lib/TopSitesFeed.jsm:329:11
01:45:36 INFO - asyncrefresh@resource://activity-stream/lib/TopSitesFeed.jsm:352:25
01:45:36 INFO - asynconAction@resource://activity-stream/lib/TopSitesFeed.jsm:652:9
01:45:36 INFO - _middleware/</<@resource://activity-stream/lib/Store.jsm:51:11
01:45:36 INFO - Store/this[method]@resource://activity-stream/lib/Store.jsm:29:54
01:45:36 INFO - init/this.intervalId<@resource://activity-stream/lib/SystemTickFeed.jsm:16:41
01:45:36 INFO - notify@resource://gre/modules/Timer.jsm:42:7
01:45:36 INFO -
01:45:36 INFO - Buffered messages logged at 01:44:51
01:45:36 INFO - Longer timeout required, waiting longer... Remaining timeouts: 1
01:45:36 INFO - Buffered messages finished
01:45:36 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/mochitest/browser_webconsole_split.js | Test timed out -
01:45:36 INFO - GECKO(3348) | console.error: (new Error("Connection closed, pending request to server1.conn247.child1/performanceActor14, type canCurrentlyRecord failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol.js:1415:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol.js:1539:14\ncanCurrentlyRecord@chrome://devtools/content/performance/performance-controller.js:263:19\nasyncinitialize@chrome://devtools/content/performance/performance-view.js:136:15\nasyncstartupPerformance@chrome://devtools/content/performance/performance-controller.js:94:9\nasyncopen@resource://devtools/client/performance/panel.js:53:11\nasync*onLoad@resource://devtools/client/framework/toolbox.js:1804:21\n", "resource://devtools/shared/protocol.js", 1328))
01:45:39 INFO - Removing tab.
01:45:39 INFO - Waiting for event: 'TabClose' on [object XULElement].
01:45:39 INFO - Got event: 'TabClose' on [object XULElement].
01:45:39 INFO - Tab removed and finished closing
01:45:39 INFO - GECKO(3348) | MEMORY STAT | vsize 1786MB | vsizeMaxContiguous 25MB | residentFast 879MB | heapAllocated 336MB

Comment 5

[Intermittent Failures Robot]

1 failures in 2383 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1490553&startday=2019-01-21&endday=2019-01-27&tree=all

Comment 6

[Intermittent Failures Robot]

1 failures in 2658 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-inbound: 1

Platform breakdown:

• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1490553&startday=2019-02-04&endday=2019-02-10&tree=all

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 6
• mozilla-central: 3
• mozilla-inbound: 1

Platform breakdown:

• windows7-32: 10

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

Comment 8

[Natalia Csoregi [:nataliaCs]]

Update:
There have been 37 failures within the last 7 days, all of them on Windows 7 pgo

Recent log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=232115856&repo=mozilla-inbound&lineNumber=3362

Comment 9

[Intermittent Failures Robot]

39 failures in 2818 pushes (0.014 failures/push) were associated with this bug in the last 7 days.

This is the #49 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 20
• mozilla-inbound: 11
• mozilla-central: 8

Platform breakdown:

• windows7-32: 39

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1490553&startday=2019-03-04&endday=2019-03-10&tree=all

Comment 10

[Intermittent Failures Robot]

10 failures in 2771 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 7
• mozilla-inbound: 1
• mozilla-central: 1
• try: 1

Platform breakdown:

• windows7-32: 9
• windows7-32-nightly: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1490553&startday=2019-03-11&endday=2019-03-17&tree=all

Comment 11

[Intermittent Failures Robot]

3 failures in 2561 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1
• autoland: 2

Platform breakdown:

• windows7-32: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1490553&startday=2019-03-18&endday=2019-03-24&tree=all

Comment 12

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Comment 13

[Natalia Csoregi [:nataliaCs]]

https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=253559534&repo=autoland&lineNumber=35443

Comment 14

[Intermittent Failures Robot]

1 failures in 4409 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1490553&startday=2019-06-24&endday=2019-06-30&tree=all

Comment 15

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Comment 16

[Intermittent Failures Robot]

1 failures in 5073 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1490553&startday=2019-08-19&endday=2019-08-25&tree=all

Comment 17

[Intermittent Failures Robot]

1 failures in 4004 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-beta: 1

Platform breakdown:

• windows7-32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1490553&startday=2020-01-20&endday=2020-01-26&tree=all