Intermittent Win8 PGO test_bug656379-1.html | Test timed out | test_bug656379-2.html | Test timed out | more failures and eventually "4 test timeouts, giving up."

RESOLVED FIXED in Firefox 34

Status

()

defect
RESOLVED FIXED
5 years ago
4 years ago

People

(Reporter: RyanVM, Assigned: poiru)

Tracking

({intermittent-failure})

Trunk
mozilla36
x86
Windows 8
Points:
---
Bug Flags:
in-testsuite +

Firefox Tracking Flags

(firefox34 fixed, firefox35 fixed, firefox36 fixed, firefox-esr31 wontfix)

Details

Attachments

(2 attachments)

Reporter

Description

5 years ago
Posted image test screenshot
https://tbpl.mozilla.org/php/getParsedLog.php?id=33052701&tree=Fx-Team

WINNT 6.2 fx-team pgo test mochitest-1 on 2014-01-15 12:29:24 PST for push 59251ffa1853
slave: t-w864-ix-023

12:43:17     INFO -  85220 INFO TEST-START | /tests/content/events/test/test_bug656379-1.html
12:43:17     INFO -  85221 INFO TEST-PASS | /tests/content/events/test/test_bug656379-1.html | Pressed focused buttons should look different from normal buttons. - got undefined and undefined
12:43:17     INFO -  85222 INFO TEST-PASS | /tests/content/events/test/test_bug656379-1.html | Pressed focused buttons should look different from normal focused buttons. - got undefined and undefined
12:44:12     INFO -  NOTE: child process received `Goodbye', closing down
12:44:12     INFO -  [4048] WARNING: pipe error: 109: file c:/builds/moz2_slave/fx-team-w32-pgo-00000000000000/build/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 312
12:44:12     INFO -  NPP_NewStream
12:44:12     INFO -  NPP_DestroyStream
12:44:12     INFO -  NPP_NewStream
12:44:12     INFO -  NPP_DestroyStream
12:44:12     INFO -  NPP_Destroy
12:44:12     INFO -  NPP_Destroy
12:44:12     INFO -  NPP_Destroy
12:44:12     INFO -  NPP_NewStream
12:44:12     INFO -  NPP_StreamAsFile, file=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp\plugtmp-23\plugin-file_mixed_content_server.sjs
12:44:12     INFO -  NPP_DestroyStream
12:44:12     INFO -  NPP_Destroy
12:44:12     INFO -  NPP_NewStream
12:44:12     INFO -  NPP_StreamAsFile, file=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp\plugtmp-23\plugin-file_mixed_content_server.sjs
12:44:12     INFO -  NPP_DestroyStream
12:44:12     INFO -  NPP_Destroy
12:44:12     INFO -  NPP_Destroy
12:44:12     INFO -  NPP_Destroy
12:44:12     INFO -  NPP_Destroy
12:44:12     INFO -  NPP_Destroy
12:44:12     INFO -  NPP_Destroy
12:44:12     INFO -  NPP_Destroy
12:44:12     INFO -  NPP_Destroy
12:44:12     INFO -  NPP_Destroy
12:44:12     INFO -  NPP_Destroy
12:44:12     INFO -  NPP_Destroy
12:44:12     INFO -  NPP_Destroy
12:44:12     INFO -  NPP_Destroy
12:44:12     INFO -  NPP_Destroy
12:44:12     INFO -  NPP_Destroy
12:44:12     INFO -  NPP_Destroy
12:44:12     INFO -  NPP_Destroy
12:44:12     INFO -  NPP_Destroy
12:44:12     INFO -  NPP_Destroy
12:48:21     INFO -  SCREENSHOT: 
12:48:21     INFO -  85223 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_bug656379-1.html | Test timed out.
12:48:21     INFO -  85224 INFO TEST-INFO | MEMORY STAT vsize after test: 901087232
12:48:21     INFO -  85225 INFO TEST-INFO | MEMORY STAT vsizeMaxContiguous after test: 1893662720
12:48:21     INFO -  85226 INFO TEST-INFO | MEMORY STAT residentFast after test: 193572864
12:48:21     INFO -  85227 INFO TEST-INFO | MEMORY STAT heapAllocated after test: 99140958
12:48:21     INFO -  85228 INFO TEST-END | /tests/content/events/test/test_bug656379-1.html | finished in 304168ms
12:48:24     INFO -  85229 INFO Error: Unable to restore focus, expect failures and timeouts.
12:48:24     INFO -  85230 INFO TEST-START | /tests/content/events/test/test_bug656379-2.html
12:48:27     INFO -  85231 INFO Error: Unable to restore focus, expect failures and timeouts.
12:48:27     INFO -  85232 INFO TEST-INFO | /tests/content/events/test/test_bug656379-2.html | must wait for load
12:48:27     INFO -  85233 INFO TEST-INFO | /tests/content/events/test/test_bug656379-2.html | must wait for focus
12:53:50     INFO -  Not taking screenshot here: see the one that was previously logged
12:53:50     INFO -  85234 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_bug656379-2.html | Test timed out.
12:53:51     INFO -  85235 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_bug656379-2.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
12:53:51     INFO -  85236 INFO TEST-INFO | MEMORY STAT vsize after test: 925646848
12:53:51     INFO -  85237 INFO TEST-INFO | MEMORY STAT vsizeMaxContiguous after test: 1893662720
12:53:51     INFO -  85238 INFO TEST-INFO | MEMORY STAT residentFast after test: 220532736
12:53:51     INFO -  85239 INFO TEST-INFO | MEMORY STAT heapAllocated after test: 127410850
12:53:51     INFO -  85240 INFO TEST-END | /tests/content/events/test/test_bug656379-2.html | finished in 327087ms
12:53:54     INFO -  85241 INFO Error: Unable to restore focus, expect failures and timeouts.
12:53:55     INFO -  85242 INFO TEST-START | /tests/content/events/test/test_bug656954.html
12:53:58     INFO -  85243 INFO Error: Unable to restore focus, expect failures and timeouts.
12:53:58     INFO -  85244 INFO TEST-PASS | /tests/content/events/test/test_bug656954.html | After creating event defaultPrevented should be false
12:53:58     INFO -  85245 INFO TEST-PASS | /tests/content/events/test/test_bug656954.html | preventDefault() should have been called!
12:53:58     INFO -  85246 INFO TEST-PASS | /tests/content/events/test/test_bug656954.html | preventDefault() should not have any effect!
12:53:58     INFO -  85247 INFO TEST-INFO | MEMORY STAT vsize after test: 885366784
12:53:58     INFO -  85248 INFO TEST-INFO | MEMORY STAT vsizeMaxContiguous after test: 1893662720
12:53:58     INFO -  85249 INFO TEST-INFO | MEMORY STAT residentFast after test: 169312256
12:53:58     INFO -  85250 INFO TEST-INFO | MEMORY STAT heapAllocated after test: 81964076
12:53:58     INFO -  85251 INFO TEST-END | /tests/content/events/test/test_bug656954.html | finished in 3073ms
12:54:01     INFO -  85252 INFO Error: Unable to restore focus, expect failures and timeouts.
12:54:01     INFO -  85253 INFO TEST-START | /tests/content/events/test/test_bug659071.html
12:54:04     INFO -  85254 INFO Error: Unable to restore focus, expect failures and timeouts.
12:54:04     INFO -  85255 INFO TEST-INFO | /tests/content/events/test/test_bug659071.html | must wait for load
12:54:04     INFO -  85256 INFO TEST-INFO | /tests/content/events/test/test_bug659071.html | must wait for focus
12:54:04     INFO -  85257 INFO TEST-PASS | /tests/content/events/test/test_bug659071.html | failed to reset zoom
12:54:04     INFO -  85258 INFO TEST-PASS | /tests/content/events/test/test_bug659071.html | Pressing the label should have pressed the button. - got undefined and undefined
12:54:04     INFO -  85259 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_bug659071.html | Moving the mouse down from the label should have unpressed the button. - expected data:image/png;base64,iVBORw0KGgoAAAANSUhEUgAAAC0AAAAUCAYAAAAZb7T/AAAAzUlEQVRIie2VbQ7DIAiGOQ/34Tzch/N4n3c/1g/q1DpdnFt8kyYSLDwgtoQfFH0boEULepT+BdogRKD4YUUYz5dUBpqhEWFQrgT376dj9aoaGkHBJLDbkBNBB2WQWGbPbgcoJ0br2Bv5Lye3xVA5/JyptnGmc9ClNWBCrvDY3vLuduFkqztt4sFboBNxL2ClmI3QzwQ5gArQZOdKRX0MuqYrE3X6dTwI5/ixu2w9M90FXfFzMX/L1SU4vxBifn31vXe5b6Hn14IepQU9Sg+q46CZUdLKDwAAAABJRU5ErkJggg== but got data:image/png;base64,iVBORw0KGgoAAAANSUhEUgAAAC8AAAAUCAYAAAAdmmTCAAAAzklEQVRIie2V7Q2EMAiGmYd9mId9mKf7cD/OD1r7gdVc9dI3MZFg4IEiBX2xYDTAFU34Ufo3eFECUEgfZA2/56uqAI/KCWlgPFGAjZGPd4fc8BpYEUjFFfZh8IFRgaTwjbWDMmbGruSPTnOJw7T5sVJ158zX4FM79gmBaUJqL7lXu3Ha7s4L2QJ64TOxI8BW3E74b5IShBM+28lacbfCezv0sM4fxwZ0H000P2QduD3zl+Edl5TYjcBJkn2jkNj32Hd+ETTh36MJP0oTfpQ+g5VACPGZeZgAAAAASUVORK5CYII=
12:54:04     INFO -  85260 INFO TEST-PASS | /tests/content/events/test/test_bug659071.html | Moving the mouse back on top of the label should have pressed the button. - expected undefined
12:54:04     INFO -  85261 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_bug659071.html | Releasing the mouse over the label should have unpressed (and focused) the button. - expected data:image/png;base64,iVBORw0KGgoAAAANSUhEUgAAAC0AAAAUCAYAAAAZb7T/AAAAzUlEQVRIie2VbQ7DIAiGOQ/34Tzch/N4n3c/1g/q1DpdnFt8kyYSLDwgtoQfFH0boEULepT+BdogRKD4YUUYz5dUBpqhEWFQrgT376dj9aoaGkHBJLDbkBNBB2WQWGbPbgcoJ0br2Bv5Lye3xVA5/JyptnGmc9ClNWBCrvDY3vLuduFkqztt4sFboBNxL2ClmI3QzwQ5gArQZOdKRX0MuqYrE3X6dTwI5/ixu2w9M90FXfFzMX/L1SU4vxBifn31vXe5b6Hn14IepQU9Sg+q46CZUdLKDwAAAABJRU5ErkJggg== but got data:image/png;base64,iVBORw0KGgoAAAANSUhEUgAAAC8AAAAUCAYAAAAdmmTCAAAAsklEQVRIie2U2w3AIAhF7xKdwiVcwiWcopvbnz7IDbZamyqJJCQiikcEkQwLegO0yAmPFWZ0wo8JD0WX/tDl8JE2+coLyBhavF/h4z5vEt4jwd2skfYCvfQ0P78m9rMgxtXwrNohOfi7zDtKAtsQdua16zPv6AJv4XkdAz7FfQUfHyBK4HN9UxO3Cd5k5rWyCbia+cuab4Zn5YYNwqfBHz9KoLH0aXGb4QfXCT/hv4C3KBtCHEt6YefUXwAAAABJRU5ErkJggg==
12:54:05     INFO -  85262 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_bug659071.html | Removing the label should have unpressed the button. - expected data:image/png;base64,iVBORw0KGgoAAAANSUhEUgAAAC0AAAAUCAYAAAAZb7T/AAAAzUlEQVRIie2VbQ7DIAiGOQ/34Tzch/N4n3c/1g/q1DpdnFt8kyYSLDwgtoQfFH0boEULepT+BdogRKD4YUUYz5dUBpqhEWFQrgT376dj9aoaGkHBJLDbkBNBB2WQWGbPbgcoJ0br2Bv5Lye3xVA5/JyptnGmc9ClNWBCrvDY3vLuduFkqztt4sFboBNxL2ClmI3QzwQ5gArQZOdKRX0MuqYrE3X6dTwI5/ixu2w9M90FXfFzMX/L1SU4vxBifn31vXe5b6Hn14IepQU9Sg+q46CZUdLKDwAAAABJRU5ErkJggg== but got data:image/png;base64,iVBORw0KGgoAAAANSUhEUgAAAC8AAAAUCAYAAAAdmmTCAAAAzklEQVRIie2V7Q2EMAiGmYd9mId9mKf7cD/OD1r7gdVc9dI3MZFg4IEiBX2xYDTAFU34Ufo3eFECUEgfZA2/56uqAI/KCWlgPFGAjZGPd4fc8BpYEUjFFfZh8IFRgaTwjbWDMmbGruSPTnOJw7T5sVJ158zX4FM79gmBaUJqL7lXu3Ha7s4L2QJ64TOxI8BW3E74b5IShBM+28lacbfCezv0sM4fxwZ0H000P2QduD3zl+Edl5TYjcBJkn2jkNj32Hd+ETTh36MJP0oTfpQ+g5VACPGZeZgAAAAASUVORK5CYII=
12:54:05     INFO -  85263 INFO TEST-PASS | /tests/content/events/test/test_bug659071.html | failed to zoom by ctrl+wheel
12:54:05     INFO -  85264 INFO TEST-INFO | MEMORY STAT vsize after test: 885567488
12:54:05     INFO -  85265 INFO TEST-INFO | MEMORY STAT vsizeMaxContiguous after test: 1893662720
12:54:05     INFO -  85266 INFO TEST-INFO | MEMORY STAT residentFast after test: 172945408
12:54:05     INFO -  85267 INFO TEST-INFO | MEMORY STAT heapAllocated after test: 62761676
12:54:05     INFO -  85268 INFO TEST-END | /tests/content/events/test/test_bug659071.html | finished in 4055ms

etc...

13:01:07     INFO -  85884 INFO TEST-START | /tests/content/events/test/test_bug946632.html
13:01:10     INFO -  85885 INFO Error: Unable to restore focus, expect failures and timeouts.
13:01:10     INFO -  85886 INFO TEST-INFO | /tests/content/events/test/test_bug946632.html | must wait for load
13:01:10     INFO -  85887 INFO TEST-INFO | /tests/content/events/test/test_bug946632.html | must wait for focus
13:06:20     INFO -  Not taking screenshot here: see the one that was previously logged
13:06:20     INFO -  85888 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_bug946632.html | Test timed out.
13:06:20     INFO -  85889 ERROR TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up.
13:06:20     INFO -  85890 ERROR TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 707 remaining tests.
13:06:21     INFO -  85891 ERROR TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | [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
13:06:21     INFO -  85892 INFO TEST-INFO | MEMORY STAT vsize after test: 847704064
13:06:21     INFO -  85893 INFO TEST-INFO | MEMORY STAT vsizeMaxContiguous after test: 1893662720
13:06:21     INFO -  85894 INFO TEST-INFO | MEMORY STAT residentFast after test: 23252992
13:06:21     INFO -  85895 INFO TEST-INFO | MEMORY STAT heapAllocated after test: 56611434
13:06:21     INFO -  85896 ERROR TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | /tests/content/events/test/test_bug946632.html finished in a non-clean fashion, probably because it didn't call SimpleTest.finish()
13:06:21     INFO -  85897 INFO TEST-END | (SimpleTest/TestRunner.js) | finished in 314665ms
Reporter

Comment 1

5 years ago
https://tbpl.mozilla.org/php/getParsedLog.php?id=33086671&tree=Fx-Team
WINNT 6.2 fx-team pgo test mochitest-1 on 2014-01-15 23:32:31 PST for push 7cbf6359300b
slave: t-w864-ix-049

https://tbpl.mozilla.org/php/getParsedLog.php?id=33086633&tree=Fx-Team
WINNT 6.2 fx-team pgo test mochitest-1 on 2014-01-15 23:37:07 PST for push dad295e99240
slave: t-w864-ix-043

https://tbpl.mozilla.org/php/getParsedLog.php?id=33087294&tree=Mozilla-Central
WINNT 6.2 mozilla-central pgo test mochitest-1 on 2014-01-15 23:56:39 PST for push bda00523b057
slave: t-w864-ix-089
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)

Updated

5 years ago
Attachment #8507640 - Flags: review?(ehsan.akhgari) → review+
Comment hidden (Legacy TBPL/Treeherder Robot)
Reporter

Comment 36

5 years ago
https://hg.mozilla.org/mozilla-central/rev/1fb91fae721b
Assignee: nobody → birunthan
Status: NEW → RESOLVED
Last Resolved: 5 years ago
Flags: in-testsuite+
Resolution: --- → FIXED
Target Milestone: --- → mozilla36
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
Comment hidden (Legacy TBPL/Treeherder Robot)
You need to log in before you can comment on or make changes to this bug.