Intermittent test_MozDomFullscreen_event.xul | Test timed out.

RESOLVED INCOMPLETE

Status

()

P5
normal
RESOLVED INCOMPLETE
2 years ago
7 months ago

People

(Reporter: intermittent-bug-filer, Unassigned)

Tracking

({intermittent-failure})

unspecified
intermittent-failure
Points:
---

Firefox Tracking Flags

(firefox50 unaffected, firefox51 affected, firefox52 wontfix)

Details

(Whiteboard: [stockwell unknown])

Comment hidden (Intermittent Failures Robot)
This and bug 1302876 regressed around the same time. And bug 1300611 looks like it landed around that time. Going to keep looking and retriggering, but I'm suspicious :)
status-firefox50: --- → unaffected
status-firefox51: --- → affected
status-firefox52: --- → affected
See Also: → bug 1302876
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
It looks like an issue on non-e10s Linux builds only.

From the screenshot, it seems the document enters fullscreen state, but the window doesn't get fullscreened.
I should probably look into this when I have time...
Flags: needinfo?(xidorn+moz)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
:xidorn, do you think you will have time this week to look at this?  Do you have questions or need more data to help fix this?
Have no luck on triggering this with my additional debug info:
https://treeherder.mozilla.org/#/jobs?repo=try&revision=49df720803bc790727e3b2af63fa5523ddfe8521
https://treeherder.mozilla.org/#/jobs?repo=try&revision=513d0a4470bed0d9f90c9ee423b85dbde99a1a73

... Probably I can push that to m-c and wait for the intermittent to show up with those info?
Flags: needinfo?(xidorn+moz)
that sounds like a reasonable next step
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
this has reduced in frequency in the last few weeks
Whiteboard: [stockwell unknown]
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Too late for firefox 52, mass-wontfix.
status-firefox52: affected → wontfix
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Summary: Intermittent dom/tests/mochitest/chrome/test_MozDomFullscreen_event.xul | Test timed out. → Intermittent test_MozDomFullscreen_event.xul | Test timed out.
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Priority: -- → P5
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
https://wiki.mozilla.org/Bugmasters#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Last Resolved: a year ago
Resolution: --- → INCOMPLETE
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Comment hidden (Intermittent Failures Robot)
Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=153225759&repo=mozilla-esr52&lineNumber=3394

[task 2017-12-23T20:29:57.762652Z] 20:29:57     INFO - TEST-START | dom/tests/mochitest/chrome/test_MozDomFullscreen_event.xul
[task 2017-12-23T20:35:26.580833Z] 20:35:26     INFO - TEST-INFO | started process screentopng
[task 2017-12-23T20:35:26.775375Z] 20:35:26     INFO - TEST-INFO | screentopng: exit 0
[task 2017-12-23T20:35:26.775434Z] 20:35:26     INFO - Buffered messages logged at 20:29:57
[task 2017-12-23T20:35:26.775521Z] 20:35:26     INFO - must wait for focus
[task 2017-12-23T20:35:26.775563Z] 20:35:26     INFO - Buffered messages finished
[task 2017-12-23T20:35:26.775669Z] 20:35:26     INFO - TEST-UNEXPECTED-FAIL | dom/tests/mochitest/chrome/test_MozDomFullscreen_event.xul | Test timed out. 
[task 2017-12-23T20:35:26.775809Z] 20:35:26     INFO - reportError@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:114:7
[task 2017-12-23T20:35:26.775874Z] 20:35:26     INFO - TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:135:7
[task 2017-12-23T20:35:27.582287Z] 20:35:27     INFO - MEMORY STAT | vsize 2025MB | residentFast 284MB | heapAllocated 74MB
[task 2017-12-23T20:35:27.585105Z] 20:35:27     INFO - TEST-OK | dom/tests/mochitest/chrome/test_MozDomFullscreen_event.xul | took 329823ms
[task 2017-12-23T20:35:27.607300Z] 20:35:27     INFO - TEST-START | dom/tests/mochitest/chrome/test_activation.xul
[task 2017-12-23T20:40:56.585306Z] 20:40:56     INFO - Not taking screenshot here: see the one that was previously logged
[task 2017-12-23T20:40:56.586341Z] 20:40:56     INFO - Buffered messages logged at 20:35:27
[task 2017-12-23T20:40:56.587089Z] 20:40:56     INFO - must wait for load
[task 2017-12-23T20:40:56.588258Z] 20:40:56     INFO - must wait for focus
[task 2017-12-23T20:40:56.589132Z] 20:40:56     INFO - Buffered messages finished
[task 2017-12-23T20:40:56.590297Z] 20:40:56     INFO - TEST-UNEXPECTED-FAIL | dom/tests/mochitest/chrome/test_activation.xul | Test timed out. 
[task 2017-12-23T20:40:56.590355Z] 20:40:56     INFO - reportError@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:114:7
[task 2017-12-23T20:40:56.590416Z] 20:40:56     INFO - TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:135:7
[task 2017-12-23T20:40:57.585564Z] 20:40:57     INFO - Not taking screenshot here: see the one that was previously logged
[task 2017-12-23T20:40:57.586769Z] 20:40:57     INFO - TEST-UNEXPECTED-FAIL | dom/tests/mochitest/chrome/test_activation.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 2017-12-23T20:40:57.587383Z] 20:40:57     INFO - SimpleTest.is@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:271:5
[task 2017-12-23T20:40:57.587724Z] 20:40:57     INFO - SimpleTest.finish/afterCleanup@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1107:13
[task 2017-12-23T20:40:57.588295Z] 20:40:57     INFO - SimpleTest.finish/executeCleanupFunction@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1149:13
[task 2017-12-23T20:40:57.588618Z] 20:40:57     INFO - SimpleTest.finish@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1168:5
[task 2017-12-23T20:40:57.589150Z] 20:40:57     INFO - killTest@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:123:7
[task 2017-12-23T20:40:57.589844Z] 20:40:57     INFO - delayedKillTest@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:150:47
[task 2017-12-23T20:40:57.591029Z] 20:40:57     INFO - MEMORY STAT | vsize 2026MB | residentFast 286MB | heapAllocated 76MB
[task 2017-12-23T20:40:57.593304Z] 20:40:57     INFO - TEST-OK | dom/tests/mochitest/chrome/test_activation.xul | took 329987ms
[task 2017-12-23T20:40:57.610665Z] 20:40:57     INFO - Not taking screenshot here: see the one that was previously logged
[task 2017-12-23T20:40:57.610933Z] 20:40:57     INFO - TEST-UNEXPECTED-FAIL | dom/tests/mochitest/chrome/test_activation.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(). - Result logged after SimpleTest.finish()
[task 2017-12-23T20:40:57.618018Z] 20:40:57     INFO - TEST-START | dom/tests/mochitest/chrome/test_bug799299.xul
[task 2017-12-23T20:46:26.596594Z] 20:46:26     INFO - Not taking screenshot here: see the one that was previously logged
[task 2017-12-23T20:46:26.596708Z] 20:46:26     INFO - Buffered messages logged at 20:40:57
[task 2017-12-23T20:46:26.596770Z] 20:46:26     INFO - must wait for load
[task 2017-12-23T20:46:26.597650Z] 20:46:26     INFO - must wait for focus
[task 2017-12-23T20:46:26.598205Z] 20:46:26     INFO - Buffered messages finished
[task 2017-12-23T20:46:26.598395Z] 20:46:26     INFO - TEST-UNEXPECTED-FAIL | dom/tests/mochitest/chrome/test_bug799299.xul | Test timed out. 
[task 2017-12-23T20:46:26.599220Z] 20:46:26     INFO - reportError@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:114:7
[task 2017-12-23T20:46:26.599486Z] 20:46:26     INFO - TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:135:7
[task 2017-12-23T20:46:27.592194Z] 20:46:27     INFO - MEMORY STAT | vsize 2040MB | residentFast 302MB | heapAllocated 81MB
[task 2017-12-23T20:46:27.594376Z] 20:46:27     INFO - TEST-OK | dom/tests/mochitest/chrome/test_bug799299.xul | took 329977ms
[task 2017-12-23T20:46:27.616026Z] 20:46:27     INFO - TEST-START | dom/tests/mochitest/chrome/test_bug800817.xul
[task 2017-12-23T20:51:56.594729Z] 20:51:56     INFO - Not taking screenshot here: see the one that was previously logged
[task 2017-12-23T20:51:56.594817Z] 20:51:56     INFO - Buffered messages logged at 20:46:27
[task 2017-12-23T20:51:56.595911Z] 20:51:56     INFO - must wait for load
[task 2017-12-23T20:51:56.596925Z] 20:51:56     INFO - must wait for focus
[task 2017-12-23T20:51:56.597632Z] 20:51:56     INFO - Buffered messages finished
[task 2017-12-23T20:51:56.597731Z] 20:51:56     INFO - TEST-UNEXPECTED-FAIL | dom/tests/mochitest/chrome/test_bug800817.xul | Test timed out. 
[task 2017-12-23T20:51:56.598370Z] 20:51:56     INFO - reportError@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:114:7
[task 2017-12-23T20:51:56.599028Z] 20:51:56     INFO - TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:135:7
[task 2017-12-23T20:51:56.599649Z] 20:51:56     INFO - Not taking screenshot here: see the one that was previously logged
[task 2017-12-23T20:51:56.600228Z] 20:51:56     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up. 
[task 2017-12-23T20:51:56.600852Z] 20:51:56     INFO - reportError@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:114:7
[task 2017-12-23T20:51:56.600998Z] 20:51:56     INFO - TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:143:9
[task 2017-12-23T20:51:56.601612Z] 20:51:56     INFO - Not taking screenshot here: see the one that was previously logged
[task 2017-12-23T20:51:56.602254Z] 20:51:56     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 26 remaining tests. 
[task 2017-12-23T20:51:56.602406Z] 20:51:56     INFO - reportError@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:114:7
[task 2017-12-23T20:51:56.603067Z] 20:51:56     INFO - TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:145:9
[task 2017-12-23T20:51:57.598032Z] 20:51:57     INFO - MEMORY STAT | vsize 2052MB | residentFast 308MB | heapAllocated 86MB
[task 2017-12-23T20:51:57.599594Z] 20:51:57     INFO - Not taking screenshot here: see the one that was previously logged
[task 2017-12-23T20:51:57.600983Z] 20:51:57     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | chrome://mochitests/content/chrome/dom/tests/mochitest/chrome/test_bug800817.xul - finished in a non-clean fashion, probably because it didn't call SimpleTest.finish()
[task 2017-12-23T20:51:57.601130Z] 20:51:57     INFO - {u'loaded_test_url': u'chrome://mochitests/content/chrome/dom/tests/mochitest/chrome/test_bug800817.xul'}
[task 2017-12-23T20:51:57.602205Z] 20:51:57     INFO - TEST-OK | (SimpleTest/TestRunner.js)
[task 2017-12-23T20:51:57.619447Z] 20:51:57     INFO - Not taking screenshot here: see the one that was previously logged
[task 2017-12-23T20:51:57.619684Z] 20:51:57     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | undefined assertion name - Result logged after SimpleTest.finish()
[task 2017-12-23T20:51:57.637171Z] 20:51:57     INFO - TEST-START | Shutdown
[task 2017-12-23T20:51:57.638274Z] 20:51:57     INFO - Passed:  8
[task 2017-12-23T20:51:57.638544Z] 20:51:57     INFO - Failed:  10
[task 2017-12-23T20:51:57.639514Z] 20:51:57     INFO - Todo:    0
[task 2017-12-23T20:51:57.640473Z] 20:51:57     INFO - Mode:    non-e10s
[task 2017-12-23T20:51:57.641593Z] 20:51:57     INFO - Slowest: 329987ms - chrome://mochitests/content/chrome/dom/tests/mochitest/chrome/test_activation.xul
[task 2017-12-23T20:51:57.642502Z] 20:51:57     INFO - SimpleTest FINISHED
[task 2017-12-23T20:51:57.643546Z] 20:51:57     INFO - TEST-INFO | Ran 1 Loops
[task 2017-12-23T20:51:57.644564Z] 20:51:57     INFO - SimpleTest FINISHED
[task 2017-12-23T20:51:58.019408Z] 20:51:58     INFO - TEST-INFO | Main app process: exit 0
[task 2017-12-23T20:51:58.020170Z] 20:51:58     INFO - runtests.py | Application ran for: 0:22:04.226279
[task 2017-12-23T20:51:58.020254Z] 20:51:58     INFO - zombiecheck | Reading PID log: /tmp/tmpmXSGCvpidlog
[task 2017-12-23T20:51:58.020365Z] 20:51:58     INFO - ==> process 2876 launched child process 2901
[task 2017-12-23T20:51:58.020586Z] 20:51:58     INFO - zombiecheck | Checking for orphan process with PID: 2901
[task 2017-12-23T20:51:58.020729Z] 20:51:58     INFO - Stopping web server
[task 2017-12-23T20:51:58.048875Z] 20:51:58     INFO - Stopping web socket server
[task 2017-12-23T20:51:58.068980Z] 20:51:58     INFO - Stopping ssltunnel
[task 2017-12-23T20:51:58.089410Z] 20:51:58  WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2017-12-23T20:51:58.090269Z] 20:51:58     INFO - runtests.py | Running tests: end.
[task 2017-12-23T20:51:58.109603Z] 20:51:58     INFO - Buffered messages finished
[task 2017-12-23T20:51:58.110455Z] 20:51:58     INFO -  dir: dom/tests/mochitest/general
[task 2017-12-23T20:51:58.136284Z] 20:51:58     INFO -  Setting pipeline to PAUSED ...
[task 2017-12-23T20:51:58.137381Z] 20:51:58     INFO -  Pipeline is PREROLLING ...
[task 2017-12-23T20:51:58.138443Z] 20:51:58     INFO -  Pipeline is PREROLLED ...
[task 2017-12-23T20:51:58.139332Z] 20:51:58     INFO -  Setting pipeline to PLAYING ...
[task 2017-12-23T20:51:58.140239Z] 20:51:58     INFO -  New clock: GstSystemClock
[task 2017-12-23T20:51:58.174311Z] 20:51:58     INFO -  Got EOS from element "pipeline0".
[task 2017-12-23T20:51:58.175231Z] 20:51:58     INFO -  Execution ended after 33126593 ns.
[task 2017-12-23T20:51:58.175753Z] 20:51:58     INFO -  Setting pipeline to PAUSED ...
[task 2017-12-23T20:51:58.176573Z] 20:51:58     INFO -  Setting pipeline to READY ...
[task 2017-12-23T20:51:58.176693Z] 20:51:58     INFO -  Setting pipeline to NULL ...
[task 2017-12-23T20:51:58.177317Z] 20:51:58     INFO -  Freeing pipeline ...
[task 2017-12-23T20:51:58.217340Z] 20:51:58     INFO -  17
[task 2017-12-23T20:51:58.245089Z] 20:51:58     INFO -  mozprofile.addons WARNING | Could not install /home/worker/workspace/build/tests/mochitest/extensions/mozscreenshots: [Errno 2] No such file or directory: '/home/worker/workspace/build/tests/mochitest/extensions/mozscreenshots/install.rdf'
[task 2017-12-23T20:51:58.356995Z] 20:51:58     INFO -  pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2017-12-23T20:51:58.486851Z] 20:51:58     INFO - MochitestServer : launching [u'/home/worker/workspace/build/tests/bin/xpcshell', '-g', '/home/worker/workspace/build/application/firefox', '-v', '170', '-f', '/home/worker/workspace/build/tests/bin/components/httpd.js', '-e', "const _PROFILE_PATH = '/tmp/tmp2s4RxK.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', '/home/worker/workspace/build/tests/mochitest/server.js']
[task 2017-12-23T20:51:58.486924Z] 20:51:58     INFO - runtests.py | Server pid: 3017
[task 2017-12-23T20:51:58.497066Z] 20:51:58     INFO - runtests.py | Websocket server pid: 3020
[task 2017-12-23T20:51:58.518885Z] 20:51:58     INFO - runtests.py | SSL tunnel pid: 3024
[task 2017-12-23T20:51:58.673390Z] 20:51:58     INFO - runtests.py | Running with e10s: False
[task 2017-12-23T20:51:58.673615Z] 20:51:58     INFO - runtests.py | Running tests: start.
[task 2017-12-23T20:51:58.673719Z] 20:51:58     INFO - 
[task 2017-12-23T20:51:58.673836Z] 20:51:58     INFO - Application command: /home/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmp2s4RxK.mozrunner
[task 2017-12-23T20:51:58.690196Z] 20:51:58     INFO - runtests.py | Application pid: 3047
[task 2017-12-23T20:51:58.690400Z] 20:51:58     INFO - TEST-INFO | started process Main app process
[task 2017-12-23T20:51:59.842212Z] 20:51:59     INFO - 1514062319837	Marionette	INFO	Listening on port 2828
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: REOPENED → RESOLVED
Last Resolved: a year ago9 months ago
Resolution: --- → INCOMPLETE
Comment hidden (Intermittent Failures Robot)
You need to log in before you can comment on or make changes to this bug.