Closed Bug 886782 Opened 11 years ago Closed 9 years ago

Intermittent test_bug265203.html | Test timed out. | [SimpleTest.finish()] waitForFocus() was called a different number of times ... (followed by the same in test_bug369306.html)

Categories

(Core :: DOM: Events, defect)

x86
Windows 8
defect
Not set
normal

Tracking

()

RESOLVED WORKSFORME

People

(Reporter: emorley, Unassigned)

References

Details

(Keywords: intermittent-failure)

Attachments

(1 file, 1 obsolete file)

+++ This bug was initially created as a clone of Bug #556622 +++

Breaking out from bug 556622 since Win8-only and a different failure mode.


(In reply to TinderboxPushlog Robot from comment #126)
> edmorley
> https://tbpl.mozilla.org/php/getParsedLog.php?id=22545630&tree=Mozilla-
> Inbound
> WINNT 6.2 mozilla-inbound opt test mochitest-3 on 2013-05-03 02:42:18
> slave: t-w864-ix-070
> 
> 03:21:13     INFO -  23823 ERROR TEST-UNEXPECTED-FAIL |
> /tests/dom/tests/mochitest/bugs/test_bug265203.html | Test timed out.
> 03:21:14     INFO -  23824 ERROR TEST-UNEXPECTED-FAIL |
> /tests/dom/tests/mochitest/bugs/test_bug265203.html | [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
> 03:27:43     INFO -  23906 ERROR TEST-UNEXPECTED-FAIL |
> /tests/dom/tests/mochitest/bugs/test_bug369306.html | Test timed out.
> 03:27:44     INFO -  23907 ERROR TEST-UNEXPECTED-FAIL |
> /tests/dom/tests/mochitest/bugs/test_bug369306.html | [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
WINNT 6.2 birch pgo test mochitest-3 on 2013-06-25 03:30:43 PDT for push f2f4d89e688a

slave: t-w864-ix-105

https://tbpl.mozilla.org/php/getParsedLog.php?id=24562508&tree=Birch

{
04:01:50     INFO -  25092 INFO TEST-START | /tests/dom/tests/mochitest/bugs/test_bug260264_nested.html
04:01:53     INFO -  25093 INFO Error: Unable to restore focus, expect failures and timeouts.
04:01:53     INFO -  25094 INFO TEST-PASS | /tests/dom/tests/mochitest/bugs/test_bug260264_nested.html | popup should not have been blocked
04:01:53     INFO -  25095 INFO TEST-PASS | /tests/dom/tests/mochitest/bugs/test_bug260264_nested.html | popup should not have been blocked
04:01:53     INFO -  25096 INFO TEST-PASS | /tests/dom/tests/mochitest/bugs/test_bug260264_nested.html | popup should not have been blocked
04:01:53     INFO -  25097 INFO TEST-PASS | /tests/dom/tests/mochitest/bugs/test_bug260264_nested.html | popup should have been blocked
04:01:53     INFO -  25098 INFO TEST-PASS | /tests/dom/tests/mochitest/bugs/test_bug260264_nested.html | popup should have been blocked
04:01:54     INFO -  25099 INFO TEST-PASS | /tests/dom/tests/mochitest/bugs/test_bug260264_nested.html | popup should not have been blocked
04:01:54     INFO -  25100 INFO TEST-PASS | /tests/dom/tests/mochitest/bugs/test_bug260264_nested.html | popup should not have been blocked
04:01:54     INFO -  25101 INFO TEST-PASS | /tests/dom/tests/mochitest/bugs/test_bug260264_nested.html | popup should not have been blocked
04:01:54     INFO -  25102 INFO TEST-END | /tests/dom/tests/mochitest/bugs/test_bug260264_nested.html | finished in 3692ms
04:01:57     INFO -  25103 INFO Error: Unable to restore focus, expect failures and timeouts.
04:01:57     INFO -  25104 INFO TEST-START | /tests/dom/tests/mochitest/bugs/test_bug265203.html
04:02:00     INFO -  25105 INFO Error: Unable to restore focus, expect failures and timeouts.
04:02:00     INFO -  25106 INFO TEST-INFO | /tests/dom/tests/mochitest/bugs/test_bug265203.html | must wait for load
04:02:00     INFO -  25107 INFO TEST-INFO | /tests/dom/tests/mochitest/bugs/test_bug265203.html | must wait for focus
04:06:57     INFO -  25108 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/bugs/test_bug265203.html | Test timed out.
04:06:58     INFO -  25109 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/bugs/test_bug265203.html | [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
04:06:58     INFO -  25110 INFO TEST-END | /tests/dom/tests/mochitest/bugs/test_bug265203.html | finished in 301378ms
}
Attached image Screenshot (obsolete) —
So this is happening because focus isn't getting restored after the test for bug 260264 runs, the waitForFocus that starts the test times out, and that then complains. I don't know why test_bug260264.html screws things up, though. That test opens and closes a bunch of popup windows, but not sure how the browser ends up being minimized (which seems to be what the problem is judging by the screenshot).
Attached image test screenshot
(In reply to :Gijs Kruitbosch from comment #517)
> So this is happening because focus isn't getting restored after the test for
> bug 260264 runs, the waitForFocus that starts the test times out, and that
> then complains. I don't know why test_bug260264.html screws things up,
> though. That test opens and closes a bunch of popup windows, but not sure
> how the browser ends up being minimized (which seems to be what the problem
> is judging by the screenshot).

FWIW, the original screenshot was taken when the harness was buggy and basically every Win8 screenshot looked like that. Attached is a current one. Looks like the library is somehow ending up in front?

Ted, do you have any ideas what might be going on here?
Attachment #767161 - Attachment is obsolete: true
Flags: needinfo?(ted)
Nothing springs to mind, sorry. Are there any tests prior to this that try to reveal a file or something like that that would result in explorer being opened?
Flags: needinfo?(ted)
Looking at the recent test logs, it seems to be test_NPNVdocumentOrigin.html which is messing up the focus, since the "Error: Unable to restore focus, expect failures and timeouts" message starts to happen after that test finishes.

Looking at that test, it does something a bit awkward, it opens a window, and closes it without hitting the event loop.  Neil, could that somehow be screwing up the focus manager?

Anyways, let's see if changing that test to wait for that window to load will change anything:

https://hg.mozilla.org/integration/mozilla-inbound/rev/c41d3898b1c3
Flags: needinfo?(enndeakin)
Keywords: leave-open
The last three comments have all been on pushes that did not contain my fix.
(In reply to TBPL Robot from comment #878)
> philor
> https://tbpl.mozilla.org/php/getParsedLog.php?id=36232795&tree=Mozilla-
> Central
> WINNT 6.2 mozilla-central pgo test mochitest-3 on 2014-03-16 06:20:55
> revision: e3b76b155ca4
> slave: t-w864-ix-077

OK, this is after my trial fix.  This is probably a focus manager bug.  Neil, can you please take a look?
Assignee: nobody → enndeakin
See Also: → 772823
This doesn't seem to occur any more.
Assignee: enndeakin → nobody
Flags: needinfo?(enndeakin)
Status: NEW → RESOLVED
Closed: 9 years ago
Resolution: --- → WORKSFORME
Removing leave-open keyword from resolved bugs, per :sylvestre.
Keywords: leave-open
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: