854829 - Intermittent test_wheel_default_action.html | Test timed out. | [SimpleTest.finish()] this test already called finish! | called finish() multiple times

Status: RESOLVED WORKSFORME

(Core :: DOM: Events, defect)

Description

[Ed Morley [:emorley]]

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

Rev3 WINNT 6.1 mozilla-central debug test mochitest-1 on 2013-03-25 03:40:16 PDT for push 631d57b31bb1

slave: talos-r3-w7-054

https://tbpl.mozilla.org/php/getParsedLog.php?id=21053693&tree=Firefox

{
04:06:13     INFO -  107516 INFO TEST-PASS | /tests/content/events/test/test_wheel_default_action.html | doTestActionOverride(action=0, override_x=0, deltaX=1, deltaY=0.5): scrolled horizontal
04:06:14     INFO -  107517 INFO TEST-PASS | /tests/content/events/test/test_wheel_default_action.html | doTestActionOverride(action=0, override_x=1, deltaX=1, deltaY=0.5): not scrolled down, got 1009
04:06:14     INFO -  107518 INFO TEST-PASS | /tests/content/events/test/test_wheel_default_action.html | doTestActionOverride(action=0, override_x=1, deltaX=1, deltaY=0.5): not scrolled to right, got 1006
04:06:14     INFO -  107519 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_wheel_default_action.html | Test timed out.
04:06:14     INFO -  args: ['c:\\talos-slave\\test\\build\\tests\\bin\\screenshot.exe', 'c:\\users\\cltbld\\appdata\\local\\temp\\mozilla-test-fail_ecks8q']
04:06:15     INFO -  SCREENSHOT: <snip>
04:06:15     INFO -  107520 INFO TEST-PASS | /tests/content/events/test/test_wheel_default_action.html | doTestActionOverride(action=1, override_x=-1, deltaX=0.5, deltaY=1): not scrolled down, got 1019
04:06:15     INFO -  107521 INFO TEST-PASS | /tests/content/events/test/test_wheel_default_action.html | doTestActionOverride(action=1, override_x=-1, deltaX=0.5, deltaY=1): not scrolled to right, got 1003
04:06:15     INFO -  107522 INFO TEST-PASS | /tests/content/events/test/test_wheel_default_action.html | doTestActionOverride(action=1, override_x=0, deltaX=0.5, deltaY=1): not scrolled down, got 1019
04:06:15     INFO -  107523 INFO TEST-PASS | /tests/content/events/test/test_wheel_default_action.html | doTestActionOverride(action=1, override_x=0, deltaX=0.5, deltaY=1): not scrolled to right, got 1003
04:06:15     INFO -  107524 INFO TEST-PASS | /tests/content/events/test/test_wheel_default_action.html | doTestActionOverride(action=1, override_x=1, deltaX=0.5, deltaY=1): not scrolled down, got 1019
04:06:15     INFO -  107525 INFO TEST-PASS | /tests/content/events/test/test_wheel_default_action.html | doTestActionOverride(action=1, override_x=1, deltaX=0.5, deltaY=1): not scrolled to right, got 1003
04:06:15     INFO -  107526 INFO TEST-END | /tests/content/events/test/test_wheel_default_action.html | finished in 303643ms
04:06:15     INFO -  107527 INFO TEST-PASS | /tests/content/events/test/test_wheel_default_action.html | doTestActionOverride(action=0, override_x=-1, deltaX=0.5, deltaY=1): scrolled vertical
04:06:15     INFO -  107528 INFO TEST-PASS | /tests/content/events/test/test_wheel_default_action.html | doTestActionOverride(action=0, override_x=-1, deltaX=0.5, deltaY=1): scrolled horizontal
04:06:15     INFO -  107529 INFO TEST-PASS | /tests/content/events/test/test_wheel_default_action.html | doTestActionOverride(action=0, override_x=0, deltaX=0.5, deltaY=1): scrolled vertical
04:06:15     INFO -  107530 INFO TEST-PASS | /tests/content/events/test/test_wheel_default_action.html | doTestActionOverride(action=0, override_x=0, deltaX=0.5, deltaY=1): scrolled horizontal
04:06:16     INFO -  107531 INFO TEST-PASS | /tests/content/events/test/test_wheel_default_action.html | doTestActionOverride(action=0, override_x=1, deltaX=0.5, deltaY=1): scrolled vertical
04:06:16     INFO -  107532 INFO TEST-PASS | /tests/content/events/test/test_wheel_default_action.html | doTestActionOverride(action=0, override_x=1, deltaX=0.5, deltaY=1): scrolled horizontal
04:06:16     INFO -  107533 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_wheel_default_action.html | [SimpleTest.finish()] this test already called finish!
04:06:16     INFO -  107534 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_wheel_default_action.html | called finish() multiple times
04:06:16     INFO -  [Parent 2908] WARNING: getting z level of unregistered window: file e:/builds/moz2_slave/m-cen-w32-d-000000000000000000/build/xpfe/appshell/src/nsWindowMediator.cpp, line 558
04:06:16     INFO -  [Parent 2908] WARNING: getting z level of unregistered window: file e:/builds/moz2_slave/m-cen-w32-d-000000000000000000/build/xpfe/appshell/src/nsWindowMediator.cpp, line 558
04:06:16     INFO -  [Parent 2908] WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x8000FFFF: file e:/builds/moz2_slave/m-cen-w32-d-000000000000000000/build/content/base/src/nsContentUtils.cpp, line 3054
04:06:16     INFO -  [Parent 2908] WARNING: NS_ENSURE_TRUE(pusher.Push(aBoundElement)) failed: file e:/builds/moz2_slave/m-cen-w32-d-000000000000000000/build/content/xbl/src/nsXBLProtoImplMethod.cpp, line 308
04:06:16     INFO -  [Parent 2908] WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x8000FFFF: file e:/builds/moz2_slave/m-cen-w32-d-000000000000000000/build/content/base/src/nsContentUtils.cpp, line 3054
04:06:16     INFO -  [Parent 2908] WARNING: NS_ENSURE_TRUE(pusher.Push(aBoundElement)) failed: file e:/builds/moz2_slave/m-cen-w32-d-000000000000000000/build/content/xbl/src/nsXBLProtoImplMethod.cpp, line 308
04:06:16     INFO -  [Parent 2908] WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x8000FFFF: file e:/builds/moz2_slave/m-cen-w32-d-000000000000000000/build/content/base/src/nsContentUtils.cpp, line 3054
04:06:16     INFO -  [Parent 2908] WARNING: NS_ENSURE_TRUE(pusher.Push(aBoundElement)) failed: file e:/builds/moz2_slave/m-cen-w32-d-000000000000000000/build/content/xbl/src/nsXBLProtoImplMethod.cpp, line 308
04:06:16     INFO -  [Parent 2908] WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x8000FFFF: file e:/builds/moz2_slave/m-cen-w32-d-000000000000000000/build/content/base/src/nsContentUtils.cpp, line 3054
04:06:16     INFO -  [Parent 2908] WARNING: NS_ENSURE_TRUE(pusher.Push(aBoundElement)) failed: file e:/builds/moz2_slave/m-cen-w32-d-000000000000000000/build/content/xbl/src/nsXBLProtoImplMethod.cpp, line 308
04:06:16     INFO -  [Parent 2908] WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x8000FFFF: file e:/builds/moz2_slave/m-cen-w32-d-000000000000000000/build/content/base/src/nsContentUtils.cpp, line 3054
04:06:16     INFO -  [Parent 2908] WARNING: NS_ENSURE_TRUE(pusher.Push(aBoundElement)) failed: file e:/builds/moz2_slave/m-cen-w32-d-000000000000000000/build/content/xbl/src/nsXBLProtoImplMethod.cpp, line 308
04:06:16     INFO -  --DOCSHELL 04B52318 == 9 [id = 361]
04:06:16     INFO -  [Parent 2908] WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x8000FFFF: file e:/builds/moz2_slave/m-cen-w32-d-000000000000000000/build/content/base/src/nsContentUtils.cpp, line 3054
04:06:16     INFO -  [Parent 2908] WARNING: NS_ENSURE_TRUE(pusher.Push(aBoundElement)) failed: file e:/builds/moz2_slave/m-cen-w32-d-000000000000000000/build/content/xbl/src/nsXBLProtoImplMethod.cpp, line 308
04:06:16     INFO -  [Parent 2908] WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x8000FFFF: file e:/builds/moz2_slave/m-cen-w32-d-000000000000000000/build/content/base/src/nsContentUtils.cpp, line 3054
04:06:16     INFO -  [Parent 2908] WARNING: NS_ENSURE_TRUE(pusher.Push(aBoundElement)) failed: file e:/builds/moz2_slave/m-cen-w32-d-000000000000000000/build/content/xbl/src/nsXBLProtoImplMethod.cpp, line 308
04:06:16     INFO -  [Parent 2908] WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x8000FFFF: file e:/builds/moz2_slave/m-cen-w32-d-000000000000000000/build/content/base/src/nsContentUtils.cpp, line 3054
04:06:16     INFO -  [Parent 2908] WARNING: NS_ENSURE_TRUE(pusher.Push(aBoundElement)) failed: file e:/builds/moz2_slave/m-cen-w32-d-000000000000000000/build/content/xbl/src/nsXBLProtoImplMethod.cpp, line 308
04:06:16     INFO -  [Parent 2908] WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x8000FFFF: file e:/builds/moz2_slave/m-cen-w32-d-000000000000000000/build/content/base/src/nsContentUtils.cpp, line 3054
04:06:16     INFO -  [Parent 2908] WARNING: NS_ENSURE_TRUE(pusher.Push(aBoundElement)) failed: file e:/builds/moz2_slave/m-cen-w32-d-000000000000000000/build/content/xbl/src/nsXBLProtoImplMethod.cpp, line 308
04:06:16     INFO -  ++DOMWINDOW == 21 (18561228) [serial = 2491] [outer = 077F4130]
04:06:16     INFO -  107535 INFO TEST-START | /tests/content/html/content/test/forms/test_button_attributes_reflection.html
}

Comment 1

[(not currently active) Ted Mielczarek]

The bug title isn't really correct here, is it? It mentions that "exited with code -20", but the failure mode here is just a timeout + the test continues and winds up calling finish() twice.

Comment 2

[Ed Morley [:emorley]]

Copy pasta fail after bug clone, sorry!

Comment 3

[Legacy TBPL/Treeherder Robot]

RyanVM
https://tbpl.mozilla.org/php/getParsedLog.php?id=21400014&tree=Mozilla-Inbound
Rev3 WINNT 6.1 mozilla-inbound debug test mochitest-1 on 2013-04-03 06:22:05
slave: talos-r3-w7-069

13:49:07     INFO -  107510 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_wheel_default_action.html | Test timed out.
13:49:09     INFO -  107524 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_wheel_default_action.html | [SimpleTest.finish()] this test already called finish!
13:49:09     INFO -  107525 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_wheel_default_action.html | called finish() multiple times

Comment 4

[Legacy TBPL/Treeherder Robot]

philor
https://tbpl.mozilla.org/php/getParsedLog.php?id=21413546&tree=Mozilla-Inbound
Rev3 WINNT 6.1 mozilla-inbound debug test mochitest-1 on 2013-04-03 12:03:59
slave: talos-r3-w7-091

19:34:09     INFO -  107501 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_wheel_default_action.html | Test timed out.
19:34:11     INFO -  108219 ERROR TEST-UNEXPECTED-FAIL | /tests/content/html/content/test/forms/test_button_attributes_reflection.html | [SimpleTest.finish()] this test already called finish!
19:34:11     INFO -  108220 ERROR TEST-UNEXPECTED-FAIL | /tests/content/html/content/test/forms/test_button_attributes_reflection.html | called finish() multiple times

Comment 5

[Legacy TBPL/Treeherder Robot]

mbrubeck
https://tbpl.mozilla.org/php/getParsedLog.php?id=21517685&tree=Mozilla-Inbound
Rev3 WINNT 6.1 mozilla-inbound debug test mochitest-1 on 2013-04-06 15:32:07
slave: talos-r3-w7-048

22:58:59     INFO -  107490 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_wheel_default_action.html | Test timed out.
22:59:02     INFO -  107493 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_wheel_default_action.html | [SimpleTest.finish()] this test already called finish!
22:59:02     INFO -  107494 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_wheel_default_action.html | called finish() multiple times

Comment 6

[Legacy TBPL/Treeherder Robot]

philor
https://tbpl.mozilla.org/php/getParsedLog.php?id=21578319&tree=Mozilla-Inbound
Rev3 WINNT 6.1 mozilla-inbound debug test mochitest-1 on 2013-04-08 13:50:07
slave: talos-r3-w7-082

21:17:11     INFO -  107487 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_wheel_default_action.html | Test timed out.
21:17:13     INFO -  107503 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_wheel_default_action.html | [SimpleTest.finish()] this test already called finish!
21:17:13     INFO -  107504 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_wheel_default_action.html | called finish() multiple times
21:34:49     INFO -  207592 ERROR TEST-UNEXPECTED-FAIL | /tests/content/media/webspeech/recognition/test/test_abort.html | unexpected event: error -- No speech detected (timeout)
21:34:59     INFO -  207597 ERROR TEST-UNEXPECTED-FAIL | /tests/content/media/webspeech/recognition/test/test_abort.html | unexpected event: error -- No speech detected (timeout)
21:35:09     INFO -  207602 ERROR TEST-UNEXPECTED-FAIL | /tests/content/media/webspeech/recognition/test/test_abort.html | unexpected event: error -- No speech detected (timeout)
21:35:19     INFO -  207607 ERROR TEST-UNEXPECTED-FAIL | /tests/content/media/webspeech/recognition/test/test_abort.html | unexpected event: error -- No speech detected (timeout)
21:35:29     INFO -  207612 ERROR TEST-UNEXPECTED-FAIL | /tests/content/media/webspeech/recognition/test/test_abort.html | unexpected event: error -- No speech detected (timeout)
21:35:40     INFO -  207627 ERROR TEST-UNEXPECTED-FAIL | /tests/content/media/webspeech/recognition/test/test_call_start_from_end_handler.html | unexpected event: error -- No speech detected (timeout)
21:35:50     INFO -  207642 ERROR TEST-UNEXPECTED-FAIL | /tests/content/media/webspeech/recognition/test/test_recognition_service_error.html | expect correct error code - got 0, expected 3
21:40:50     INFO -  207643 ERROR TEST-UNEXPECTED-FAIL | /tests/content/media/webspeech/recognition/test/test_recognition_service_error.html | Test timed out.
21:41:01     INFO -  207650 ERROR TEST-UNEXPECTED-FAIL | /tests/content/media/webspeech/recognition/test/test_success_without_recognition_service.html | unexpected event: error -- No speech detected (timeout)
21:46:20     INFO -  207658 ERROR TEST-UNEXPECTED-FAIL | /tests/content/media/webspeech/recognition/test/test_timeout.html | Test timed out.
21:51:50     INFO -  207794 ERROR TEST-UNEXPECTED-FAIL | /tests/content/media/webspeech/synth/ipc/test/test_ipc.html | Test timed out.
21:51:50     INFO -  207795 ERROR TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up.
21:51:50     INFO -  207796 ERROR TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 139 remaining tests.
21:51:51     INFO -  207797 ERROR TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | /tests/content/media/webspeech/synth/ipc/test/test_ipc.html finished in a non-clean fashion, probably because it didn't call SimpleTest.finish()
21:51:56  WARNING -  TEST-UNEXPECTED-FAIL | Shutdown | Exited with code -1073741819 during test run
21:52:16  WARNING -  PROCESS-CRASH | Shutdown | application crashed [@ mozilla::dom::SynthStreamListener::DoNotifyStarted()]
21:53:20    ERROR - Return code: 1

Comment 7

[Legacy TBPL/Treeherder Robot]

RyanVM
https://tbpl.mozilla.org/php/getParsedLog.php?id=21681132&tree=Mozilla-Inbound
Rev3 WINNT 6.1 mozilla-inbound debug test mochitest-1 on 2013-04-10 21:55:25
slave: talos-r3-w7-019

05:22:42     INFO -  107541 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_wheel_default_action.html | Test timed out.
05:22:44     INFO -  108260 ERROR TEST-UNEXPECTED-FAIL | /tests/content/html/content/test/forms/test_button_attributes_reflection.html | [SimpleTest.finish()] this test already called finish!
05:22:44     INFO -  108261 ERROR TEST-UNEXPECTED-FAIL | /tests/content/html/content/test/forms/test_button_attributes_reflection.html | called finish() multiple times

Comment 8

[Legacy TBPL/Treeherder Robot]

RyanVM
https://tbpl.mozilla.org/php/getParsedLog.php?id=21977027&tree=Mozilla-Inbound
Rev3 WINNT 6.1 mozilla-inbound debug test mochitest-1 on 2013-04-18 13:17:53
slave: talos-r3-w7-042

13:45:08     INFO -  107499 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_wheel_default_action.html | Test timed out.
13:45:11     INFO -  107517 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_wheel_default_action.html | [SimpleTest.finish()] this test already called finish!
13:45:11     INFO -  107518 ERROR TEST-UNEXPECTED-FAIL | /tests/content/events/test/test_wheel_default_action.html | called finish() multiple times

Comment 9

[Ed Morley [:emorley]]

(OrangeWFM bugs not touched for 2 months)