Open Bug 1382428 Opened 3 years ago Updated 4 months ago

Intermittent layout/xul/test/browser_bug703210.js | Found a tab after previous test timed out: data:text/html,<html%20onmousemove='event.stopPropagation()'%20onmouseenter='event.stopPropagation()'%20onmouseleave='event.stopPropagation()'%20onmouseover='ev

Categories

(Core :: XUL, defect, P5)

defect

Tracking

()

Tracking Status
firefox56 --- wontfix
firefox57 --- wontfix
firefox58 --- wontfix
firefox59 --- ?

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, leave-open, Whiteboard: [stockwell disabled])

Attachments

(2 files, 1 obsolete file)

this is failing primarily on linux* debug.

Here is a recent log:
https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-central&job_id=124618404

and the related screenshot:
https://queue.taskcluster.net/v1/task/UljeAqrMQWuoHC7uNlFvUw/runs/0/artifacts/public/test_info//mozilla-test-fail-screenshot_b7MVyV.png

and here is related data from the above log:
[task 2017-08-21T18:49:38.192393Z] 18:49:38     INFO - TEST-START | layout/xul/test/browser_bug703210.js
[task 2017-08-21T18:49:38.197581Z] 18:49:38     INFO - GECKO(3049) | Chrome file doesn't exist: /home/worker/workspace/build/tests/mochitest/browser/layout/xul/test/head.js
[task 2017-08-21T18:49:38.259239Z] 18:49:38     INFO - GECKO(3049) | ++DOCSHELL 0x7fd4d61ae000 == 2 [pid = 3173] [id = {79377df9-0496-42db-8570-8d574a00b963}]
[task 2017-08-21T18:49:38.262401Z] 18:49:38     INFO - GECKO(3049) | ++DOMWINDOW == 3 (0x7fd4d61ae800) [pid = 3173] [serial = 3] [outer = (nil)]
[task 2017-08-21T18:49:38.336065Z] 18:49:38     INFO - GECKO(3049) | ++DOMWINDOW == 4 (0x7fd4d61b4800) [pid = 3173] [serial = 4] [outer = 0x7fd4d61ae800]
[task 2017-08-21T18:49:40.441880Z] 18:49:40     INFO - GECKO(3049) | --DOMWINDOW == 3 (0x7f420f1d5000) [pid = 3094] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2017-08-21T18:49:41.288445Z] 18:49:41     INFO - GECKO(3049) | --DOMWINDOW == 10 (0x7feab57a8000) [pid = 3049] [serial = 7] [outer = (nil)] [url = about:blank]
[task 2017-08-21T18:49:41.909730Z] 18:49:41     INFO - GECKO(3049) | --DOCSHELL 0x7ff45c117800 == 1 [pid = 3137] [id = {27779aa2-7b77-4f4f-9f80-4d88a74348ba}]
[task 2017-08-21T18:49:44.249556Z] 18:49:44     INFO - GECKO(3049) | --DOMWINDOW == 2 (0x7f4201365000) [pid = 3094] [serial = 4] [outer = (nil)] [url = about:blank]
[task 2017-08-21T18:49:44.862559Z] 18:49:44     INFO - GECKO(3049) | --DOCSHELL 0x7fd4e4817800 == 1 [pid = 3173] [id = {6d1e5f9e-14ce-43ec-a855-3a95057cc434}]
[task 2017-08-21T18:49:46.276242Z] 18:49:46     INFO - GECKO(3049) | --DOCSHELL 0x7f137bf17800 == 0 [pid = 3160] [id = {c78234d9-38e7-4945-938d-057a4627e82d}]
[task 2017-08-21T18:49:47.704891Z] 18:49:47     INFO - GECKO(3049) | --DOMWINDOW == 9 (0x7feabc3af000) [pid = 3049] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2017-08-21T18:49:47.708181Z] 18:49:47     INFO - GECKO(3049) | --DOMWINDOW == 8 (0x7feab510b000) [pid = 3049] [serial = 8] [outer = (nil)] [url = about:blank]
[task 2017-08-21T18:49:49.645054Z] 18:49:49     INFO - GECKO(3049) | --DOMWINDOW == 3 (0x7ff44f832800) [pid = 3137] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2017-08-21T18:49:53.792828Z] 18:49:53     INFO - GECKO(3049) | --DOMWINDOW == 2 (0x7ff44f83f000) [pid = 3137] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2017-08-21T18:49:54.378312Z] 18:49:54     INFO - GECKO(3049) | --DOMWINDOW == 1 (0x7f136f6c0800) [pid = 3160] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2017-08-21T18:49:58.286225Z] 18:49:58     INFO - GECKO(3049) | --DOMWINDOW == 0 (0x7f136f6cd000) [pid = 3160] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2017-08-21T18:50:03.208958Z] 18:50:03     INFO - GECKO(3049) | --DOMWINDOW == 3 (0x7fd4d7f33800) [pid = 3173] [serial = 1] [outer = (nil)] [url = data:text/html,<p%20id="p1"%20title="tooltip%20is%20here">This%20paragraph%20has%20a%20tooltip.</p>]
[task 2017-08-21T18:50:07.118311Z] 18:50:07     INFO - GECKO(3049) | --DOMWINDOW == 2 (0x7fd4d7f40000) [pid = 3173] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2017-08-21T18:51:08.430262Z] 18:51:08     INFO - TEST-INFO | started process screentopng
[task 2017-08-21T18:51:13.183113Z] 18:51:13     INFO - TEST-INFO | screentopng: exit 0
[task 2017-08-21T18:51:13.192531Z] 18:51:13     INFO - Buffered messages logged at 18:49:38
[task 2017-08-21T18:51:13.192823Z] 18:51:13     INFO - Entering test bound 
[task 2017-08-21T18:51:13.194246Z] 18:51:13     INFO - Buffered messages logged at 18:49:39
[task 2017-08-21T18:51:13.199669Z] 18:51:13     INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "data:text/html,<html%20onmousemove='event.stopPropagation()'%20onmouseenter='event.stopPropagation()'%20onmouseleave='event.stopPropagation()'%20onmouseover='event.stopPropagation()'%20onmouseout='event.stopPropagation()'><p%20id="p1"%20title="tooltip%20is%20here">This%20paragraph%20has%20a%20tooltip.</p><p%20id="p2">This%20paragraph%20doesn't%20have%20tooltip.</p></html>" line: 0}]
[task 2017-08-21T18:51:13.203118Z] 18:51:13     INFO - Buffered messages logged at 18:49:41
[task 2017-08-21T18:51:13.207986Z] 18:51:13     INFO - TEST-PASS | layout/xul/test/browser_bug703210.js | tooltip is showing - 
[task 2017-08-21T18:51:13.209853Z] 18:51:13     INFO - Buffered messages finished
[task 2017-08-21T18:51:13.213130Z] 18:51:13     INFO - TEST-UNEXPECTED-FAIL | layout/xul/test/browser_bug703210.js | Test timed out - 
[task 2017-08-21T18:51:13.218985Z] 18:51:13     INFO - GECKO(3049) | MEMORY STAT | vsize 2171MB | residentFast 235MB | heapAllocated 95MB
[task 2017-08-21T18:51:13.222209Z] 18:51:13     INFO - TEST-OK | layout/xul/test/browser_bug703210.js | took 90400ms
[task 2017-08-21T18:51:13.231338Z] 18:51:13     INFO - Not taking screenshot here: see the one that was previously logged
[task 2017-08-21T18:51:13.234955Z] 18:51:13     INFO - TEST-UNEXPECTED-FAIL | layout/xul/test/browser_bug703210.js | Found a tab after previous test timed out: data:text/html,<html%20onmousemove='event.stopPropagation()'%20onmouseenter='event.stopPropagation()'%20onmouseleave='event.stopPropagation()'%20onmouseover='event.stopPropagation()'%20onmouseout='event.stopPropagation()'><p%20id="p1"%20title="tooltip%20is%20here">This%20paragraph%20has%20a%20tooltip.</p><p%20id="p2">This%20paragraph%20doesn't%20have%20tooltip.</p></html> - 


:billm, I see that you reviewed a patch this test in the past- Neil is not available, would you have any tips on how to fix this?
Flags: needinfo?(wmccloskey)
Whiteboard: [stockwell needswork]
What's really odd here is that the screenshot doesn't show the document that's supposed to be displayed. I don't understand how the test's tab is getting closed unless the test actually finishes. Maybe the screenshot isn't being taken at the right time?

It might help to enable logging for the test and add an info() line right before removeCurrentTab to make sure it's not being called.
Flags: needinfo?(wmccloskey)
I ran this test 50 times on try with no failures:
https://treeherder.mozilla.org/#/jobs?repo=try&revision=3c9736c45afad95c331808bea54ff5b5b91de0d2&filter-searchStr=Linux%20x64%20debug%20Mochitests%20executed%20by%20TaskCluster%20with%20e10s%20test-linux64%2Fdebug-mochitest-browser-chrome-e10s-10%20tc-M-e10s(bc10)

All I did was add info statements (and forgot to requestCompleteLog())- so not much was happening.  I guess my odds are about 1 out of 40 for linux64-debug, so I retriggered a few more.
Attachment #8901799 - Flags: review?(gbrown) → review+
Pushed by jmaher@mozilla.com:
https://hg.mozilla.org/integration/mozilla-inbound/rev/26eeb50fc29c
Disable layout/xul/test/browser_bug703210.js for linux debug. r=gbrown
Keywords: leave-open
Whiteboard: [stockwell needswork] → [stockwell disabled]
For whatever reason, adding the info() statements seemed to make the test pass. Would it be better to keep the test with the info statements rather than disable it?
I pushed to try to enable this and see many failures:
https://treeherder.mozilla.org/#/jobs?repo=try&revision=937a7937a3059855681e579c79f9644df90c688f&selectedJob=130593501

it seems as though we don't get the popuphidden event:
http://searchfox.org/mozilla-central/source/layout/xul/test/browser_bug703210.js#25

I am happy to add more debugging or help push with other adjustments.
Priority: -- → P5
Moving to Core:XUL per https://bugzilla.mozilla.org/show_bug.cgi?id=1455336
Component: XP Toolkit/Widgets: XUL → XUL
See Also: → 1474635
Bug 1470280 appears to make this more frequent on Linux.
Attachment #9015990 - Flags: review?(jmaher)
Assignee: nobody → erahm
Status: NEW → ASSIGNED
Comment on attachment 9015990 [details] [diff] [review]
Disable browser_bug703210.js on linux

Review of attachment 9015990 [details] [diff] [review]:
-----------------------------------------------------------------

::: layout/xul/test/browser.ini
@@ +2,4 @@
>  
>  [browser_bug685470.js]
>  [browser_bug703210.js]
> +skip-if = os == 'linux' # Bug 1382428

if you could keep the verify clause, this helps us to query which tests fail in verify mode (either low value, or low hanging fruit to work on).

so an example would be:
skip-if = os == 'linux' || (verify && (os == 'linux')) # Bug 1382428
Attachment #9015990 - Flags: review?(jmaher) → review-
Blocks: 1470280
Attachment #9015990 - Attachment is obsolete: true
Attachment #9016014 - Flags: review?(jmaher) → review+
Pushed by erahm@mozilla.com:
https://hg.mozilla.org/integration/mozilla-inbound/rev/da1439fba0b6
Disable browser_bug703210.js on linux. r=jmaher
Assignee: erahm → nobody
Status: ASSIGNED → NEW
You need to log in before you can comment on or make changes to this bug.