Open Bug 1411145 Opened 7 years ago Updated 2 days ago

Intermittent accessible/tests/mochitest/relations/test_embeds.xhtml,test_embeds.xul | Test timed out.

Categories

(Core :: Disability Access APIs, defect, P5)

defect

Tracking

()

REOPENED

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

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

Attachments

(1 file, 2 obsolete files)

https://wiki.mozilla.org/Bugmasters#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=155572505&repo=mozilla-inbound&lineNumber=5711

[task 2018-01-11T09:33:03.020Z] 09:33:03     INFO - TEST-START | accessible/tests/mochitest/relations/test_embeds.xul
[task 2018-01-11T09:33:03.324Z] 09:33:03     INFO - GECKO(1086) | WARNING: content window passed to PrivateBrowsingUtils.isWindowPrivate. Use isContentWindowPrivate instead (but only for frame scripts).
[task 2018-01-11T09:33:03.327Z] 09:33:03     INFO - GECKO(1086) | pbu_isWindowPrivate@resource://gre/modules/PrivateBrowsingUtils.jsm:25:14
[task 2018-01-11T09:33:03.332Z] 09:33:03     INFO - GECKO(1086) | windowPrivacyMatches@chrome://browser/content/browser-sidebar.js:214:12
[task 2018-01-11T09:33:03.336Z] 09:33:03     INFO - GECKO(1086) | startDelayedLoad@chrome://browser/content/browser-sidebar.js:227:10
[task 2018-01-11T09:33:03.339Z] 09:33:03     INFO - GECKO(1086) | _delayedStartup/<@chrome://browser/content/browser.js:1534:7
[task 2018-01-11T09:33:03.341Z] 09:33:03     INFO - GECKO(1086) | promise callback*_delayedStartup@chrome://browser/content/browser.js:1525:5
[task 2018-01-11T09:33:03.347Z] 09:33:03     INFO - GECKO(1086) | EventListener.handleEvent*onLoad@chrome://browser/content/browser.js:1357:5
[task 2018-01-11T09:33:03.350Z] 09:33:03     INFO - GECKO(1086) | onload@chrome://browser/content/browser.xul:1:1
[task 2018-01-11T09:38:09.034Z] 09:38:09     INFO - TEST-INFO | started process screentopng
[task 2018-01-11T09:38:09.394Z] 09:38:09     INFO - TEST-INFO | screentopng: exit 0
[task 2018-01-11T09:38:09.395Z] 09:38:09     INFO - Buffered messages logged at 09:33:03
[task 2018-01-11T09:38:09.396Z] 09:38:09     INFO - must wait for load
[task 2018-01-11T09:38:09.397Z] 09:38:09     INFO - must wait for focus
[task 2018-01-11T09:38:09.398Z] 09:38:09     INFO - Buffered messages finished
[task 2018-01-11T09:38:09.399Z] 09:38:09     INFO - TEST-UNEXPECTED-FAIL | accessible/tests/mochitest/relations/test_embeds.xul | Test timed out. 
[task 2018-01-11T09:38:09.400Z] 09:38:09     INFO - reportError@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:121:7
[task 2018-01-11T09:38:09.401Z] 09:38:09     INFO - TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:142:7
[task 2018-01-11T09:38:09.402Z] 09:38:09     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:09.403Z] 09:38:09     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:09.404Z] 09:38:09     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:09.405Z] 09:38:09     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:09.406Z] 09:38:09     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:09.406Z] 09:38:09     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:09.408Z] 09:38:09     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:09.408Z] 09:38:09     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:09.409Z] 09:38:09     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:09.409Z] 09:38:09     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:09.410Z] 09:38:09     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:09.411Z] 09:38:09     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:09.412Z] 09:38:09     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:09.413Z] 09:38:09     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:09.413Z] 09:38:09     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:09.414Z] 09:38:09     INFO - TestRunner.runTests@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:380:5
[task 2018-01-11T09:38:09.414Z] 09:38:09     INFO - RunSet.runtests@chrome://mochikit/content/tests/SimpleTest/setup.js:194:3
[task 2018-01-11T09:38:09.415Z] 09:38:09     INFO - RunSet.runall@chrome://mochikit/content/tests/SimpleTest/setup.js:173:5
[task 2018-01-11T09:38:09.416Z] 09:38:09     INFO - hookupTests@chrome://mochikit/content/tests/SimpleTest/setup.js:266:5
[task 2018-01-11T09:38:09.416Z] 09:38:09     INFO - parseTestManifest@chrome://mochikit/content/manifestLibrary.js:38:5
[task 2018-01-11T09:38:09.417Z] 09:38:09     INFO - getTestManifest/req.onload@chrome://mochikit/content/manifestLibrary.js:49:11
[task 2018-01-11T09:38:09.417Z] 09:38:09     INFO - EventHandlerNonNull*getTestManifest@chrome://mochikit/content/manifestLibrary.js:45:3
[task 2018-01-11T09:38:09.418Z] 09:38:09     INFO - hookup@chrome://mochikit/content/tests/SimpleTest/setup.js:246:5
[task 2018-01-11T09:38:09.419Z] 09:38:09     INFO - linkAndHookup@chrome://mochikit/content/harness.xul:59:3
[task 2018-01-11T09:38:09.420Z] 09:38:09     INFO - parseTestManifest@chrome://mochikit/content/manifestLibrary.js:38:5
[task 2018-01-11T09:38:09.420Z] 09:38:09     INFO - getTestManifest/req.onload@chrome://mochikit/content/manifestLibrary.js:49:11
[task 2018-01-11T09:38:09.421Z] 09:38:09     INFO - EventHandlerNonNull*getTestManifest@chrome://mochikit/content/manifestLibrary.js:45:3
[task 2018-01-11T09:38:09.421Z] 09:38:09     INFO - getTestList@chrome://mochikit/content/chrome-harness.js:260:3
[task 2018-01-11T09:38:09.422Z] 09:38:09     INFO - loadTests@chrome://mochikit/content/harness.xul:38:3
[task 2018-01-11T09:38:09.422Z] 09:38:09     INFO - EventListener.handleEvent*@chrome://mochikit/content/harness.xul:62:5
[task 2018-01-11T09:38:10.037Z] 09:38:10     INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-01-11T09:38:10.039Z] 09:38:10     INFO - TEST-UNEXPECTED-FAIL | accessible/tests/mochitest/relations/test_embeds.xul | [SimpleTest.finish()] waitForFocus() was called a different number of times from the number of callbacks run.  Maybe the test terminated prematurely -- be sure to use SimpleTest.waitForExplicitFinish(). - got 1, expected +0
[task 2018-01-11T09:38:10.040Z] 09:38:10     INFO - SimpleTest.is@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:312:5
[task 2018-01-11T09:38:10.042Z] 09:38:10     INFO - afterCleanup@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1169:13
[task 2018-01-11T09:38:10.043Z] 09:38:10     INFO - executeCleanupFunction@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1211:13
[task 2018-01-11T09:38:10.045Z] 09:38:10     INFO - SimpleTest.finish@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1230:5
[task 2018-01-11T09:38:10.046Z] 09:38:10     INFO - killTest@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:130:7
[task 2018-01-11T09:38:10.047Z] 09:38:10     INFO - delayedKillTest@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:157:47
[task 2018-01-11T09:38:10.048Z] 09:38:10     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:157:7
[task 2018-01-11T09:38:10.050Z] 09:38:10     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:10.051Z] 09:38:10     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:10.052Z] 09:38:10     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:10.053Z] 09:38:10     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:10.054Z] 09:38:10     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:10.055Z] 09:38:10     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:10.056Z] 09:38:10     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:10.057Z] 09:38:10     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:10.059Z] 09:38:10     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:10.060Z] 09:38:10     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:10.061Z] 09:38:10     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:10.062Z] 09:38:10     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:10.063Z] 09:38:10     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:10.064Z] 09:38:10     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:10.066Z] 09:38:10     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:163:5
[task 2018-01-11T09:38:10.067Z] 09:38:10     INFO - TestRunner.runTests@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:380:5
[task 2018-01-11T09:38:10.068Z] 09:38:10     INFO - RunSet.runtests@chrome://mochikit/content/tests/SimpleTest/setup.js:194:3
[task 2018-01-11T09:38:10.069Z] 09:38:10     INFO - RunSet.runall@chrome://mochikit/content/tests/SimpleTest/setup.js:173:5
[task 2018-01-11T09:38:10.071Z] 09:38:10     INFO - hookupTests@chrome://mochikit/content/tests/SimpleTest/setup.js:266:5
[task 2018-01-11T09:38:10.072Z] 09:38:10     INFO - parseTestManifest@chrome://mochikit/content/manifestLibrary.js:38:5
[task 2018-01-11T09:38:10.073Z] 09:38:10     INFO - getTestManifest/req.onload@chrome://mochikit/content/manifestLibrary.js:49:11
[task 2018-01-11T09:38:10.074Z] 09:38:10     INFO - EventHandlerNonNull*getTestManifest@chrome://mochikit/content/manifestLibrary.js:45:3
[task 2018-01-11T09:38:10.075Z] 09:38:10     INFO - hookup@chrome://mochikit/content/tests/SimpleTest/setup.js:246:5
[task 2018-01-11T09:38:10.076Z] 09:38:10     INFO - linkAndHookup@chrome://mochikit/content/harness.xul:59:3
[task 2018-01-11T09:38:10.078Z] 09:38:10     INFO - parseTestManifest@chrome://mochikit/content/manifestLibrary.js:38:5
[task 2018-01-11T09:38:10.079Z] 09:38:10     INFO - getTestManifest/req.onload@chrome://mochikit/content/manifestLibrary.js:49:11
[task 2018-01-11T09:38:10.080Z] 09:38:10     INFO - EventHandlerNonNull*getTestManifest@chrome://mochikit/content/manifestLibrary.js:45:3
[task 2018-01-11T09:38:10.081Z] 09:38:10     INFO - getTestList@chrome://mochikit/content/chrome-harness.js:260:3
[task 2018-01-11T09:38:10.083Z] 09:38:10     INFO - loadTests@chrome://mochikit/content/harness.xul:38:3
[task 2018-01-11T09:38:10.084Z] 09:38:10     INFO - EventListener.handleEvent*@chrome://mochikit/content/harness.xul:62:5
[task 2018-01-11T09:38:10.085Z] 09:38:10     INFO - GECKO(1086) | MEMORY STAT | vsize 2646MB | residentFast 378MB | heapAllocated 147MB
[task 2018-01-11T09:38:10.087Z] 09:38:10     INFO - TEST-OK | accessible/tests/mochitest/relations/test_embeds.xul | took 307019ms
[task 2018-01-11T09:38:11.052Z] 09:38:11    ERROR - chrome://mochitests/content/a11y/accessible/tests/mochitest/relations/test_embeds.xul logged result after SimpleTest.finish(): [SimpleTest.finish()] waitForFocus() was called a different number of times from the number of callbacks run.  Maybe the test terminated prematurely -- be sure to use SimpleTest.waitForExplicitFinish().
[task 2018-01-11T09:38:11.070Z] 09:38:11     INFO - TEST-START | accessible/tests/mochitest/relations/test_general.html
[task 2018-01-11T09:38:11.229Z] 09:38:11     INFO - GECKO(1086) | MEMORY STAT | vsize 2646MB | residentFast 380MB | heapAllocated 150MB
[task 2018-01-11T09:38:11.245Z] 09:38:11     INFO - TEST-OK | accessible/tests/mochitest/relations/test_general.html | took 167ms
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: REOPENED → RESOLVED
Closed: 7 years ago6 years ago
Resolution: --- → INCOMPLETE
Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=168669907&repo=mozilla-inbound&lineNumber=5644
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
In the last 7 days there are 60 failures.
They occur on linux32 (opt), linux64 (opt, pgo), linux64-qr (opt), windows10-64 (pgo), linux32-nightly (opt), windows10-64 (pgo).
Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=169415787&repo=mozilla-inbound&lineNumber=5649
:surkov, can you please have a look at this?
Flags: needinfo?(surkov.alexander)
Whiteboard: [stockwell needswork]
the bug is similar to bug 1429575, and it seems the test should be rewritten to browser tests
Flags: needinfo?(surkov.alexander)
In the past 7 days there are 72 failures, most of them on Linux and Linux64.
:surkov We saw that there is some progress in ticket 1429575 which you said it's similar to this one, is there a plan to fix this?
Flags: needinfo?(surkov.alexander)
Attached patch Skipped on linux (obsolete) — Splinter Review
Attachment #8966162 - Flags: review?(gbrown)
Comment on attachment 8966162 [details] [diff] [review]
Skipped on linux

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

::: accessible/tests/mochitest/relations/a11y.ini
@@ +4,4 @@
>  
>  [test_bindings.xhtml]
>  [test_embeds.xul]
> +skip-if = (os == 'linux' && opt && bits == 32) || (os == 'linux' && opt && pgo && bits == 64)

On OF I see significant failures on
 - linux32/opt
 - linux64/opt
 - linux64/pgo

I believe there is no 'opt' condition in mozinfo: you can use '!debug' instead.

But '!debug && pgo && bits==64' would limit the skip to linux64/pgo -- it will still run on linux64/opt.


I suggest 'skip-if = os == 'linux' && !debug' instead. What do you think?
Attachment #8966162 - Flags: review?(gbrown)
Attached patch Skipped on linux (obsolete) — Splinter Review
A new patch with the suggested skip-if line. Thanks for your help.
Attachment #8966200 - Flags: review?(gbrown)
I just noticed that it should be |+skip-if = os == 'linux' && !debug # bug 1411145| not |+skip-if = os == 'linux && !debug' # bug 1411145| Is that right?
Right -- quote the os only:

skip-if = os == 'linux' && !debug

(Sorry, my comment 19 was confusing!)
Attached patch Disale on linuxSplinter Review
Attachment #8966162 - Attachment is obsolete: true
Attachment #8966200 - Attachment is obsolete: true
Attachment #8966200 - Flags: review?(gbrown)
Attachment #8966267 - Flags: review?(gbrown)
Attachment #8966267 - Flags: review?(gbrown) → review+
Bug 1429575 was landed, it probably will take couple days to confirm it was fixed. I believe same steps can be repeated for this bug. However I'm curious if the test is still actual after we moved to multiprocess Firefox.

Jamie, do you know whether RELATION_EMBEDS between a root document and a tab document is still needed for AT?
Flags: needinfo?(surkov.alexander) → needinfo?(jteh)
(In reply to alexander :surkov from comment #24)
> Jamie, do you know whether RELATION_EMBEDS between a root document and a tab
> document is still needed for AT?

Some a11y clients on Windows do depend on it; see bug 1407475. This was implemented for Linux as well in bug 1209615, so there may be Linux clients that depend on it too, though this isn't specified in the bug. However, the code to do this for e10s is very platform specific and probably doesn't work with XPCOM (and thus in tests); see the aforementioned bugs for details.
Flags: needinfo?(jteh)
Alexander, I don't see any push for bug 1429575 and the fails over there keep happening. Did you mean another bug? Maybe bug 1446671? 

Also, should we go ahead and disable this until you get a fix for it? The patch is already made by Eliza. Thanks.
Flags: needinfo?(surkov.alexander)
(In reply to Cosmin Sabou [:CosminS] from comment #26)
> Alexander, I don't see any push for bug 1429575 and the fails over there
> keep happening. Did you mean another bug? Maybe bug 1446671? 

you are correct, bug 1446671

> Also, should we go ahead and disable this until you get a fix for it? The
> patch is already made by Eliza. Thanks.

If it's really annoying, then yeah, let's disable it. Otherwise, I might be able to fix one this week.
Flags: needinfo?(surkov.alexander)
Pushed by mozilla@noorenberghe.ca:
https://hg.mozilla.org/integration/mozilla-inbound/rev/1eb0c09430e9
Disable test_embeds.xul for frequent failures on linux. r=gbrown
Keywords: checkin-needed
Whiteboard: [stockwell disable-recommended] → [stockwell disabled]
Summary: Intermittent accessible/tests/mochitest/relations/test_embeds.xul | Test timed out. → Intermittent accessible/tests/mochitest/relations/test_embeds.xhtml,test_embeds.xul | Test timed out.
Severity: normal → S3
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: