1454704 - Intermittent browser/components/payments/test/mochitest/test_payment_dialog.html | [SimpleTest.finish()] waitForFocus() was called a different number of times from the number of callbacks run. Maybe the test terminated prematurely

Status: RESOLVED INCOMPLETE

(Firefox :: WebPayments UI, defect)

Description

[Treeherder Bug Filer]

Filed by: csabou [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=174131237&repo=mozilla-inbound

https://queue.taskcluster.net/v1/task/IB0x18WcTkSuzqA5VuS1qQ/runs/0/artifacts/public/logs/live_backing.log

16:22:49     INFO -  1400 INFO TEST-START | toolkit/components/payments/test/mochitest/test_payment_dialog.html
16:28:16     INFO -  TEST-INFO | started process screenshot
16:28:16     INFO -  TEST-INFO | screenshot: exit 0
16:28:16     INFO -  Buffered messages logged at 16:22:50
16:28:16     INFO -  1401 INFO SpawnTask.js | Entering test setup_once
16:28:16     INFO -  1402 INFO must wait for load
16:28:16     INFO -  1403 INFO must wait for focus
16:28:16     INFO -  Buffered messages finished
16:28:16    ERROR -  1404 INFO TEST-UNEXPECTED-FAIL | toolkit/components/payments/test/mochitest/test_payment_dialog.html | Test timed out.
16:28:16     INFO -      reportError@SimpleTest/TestRunner.js:121:7
16:28:16     INFO -      TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
16:28:16     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
16:28:16     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
16:28:16     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
16:28:16     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
16:28:16     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
16:28:16     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
16:28:16     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
16:28:16     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
16:28:16     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
16:28:16     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
16:28:16     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
16:28:16     INFO -      TestRunner.runTests@SimpleTest/TestRunner.js:380:5
16:28:16     INFO -      RunSet.runtests@SimpleTest/setup.js:194:3
16:28:16     INFO -      RunSet.runall@SimpleTest/setup.js:173:5
16:28:16     INFO -      hookupTests@SimpleTest/setup.js:266:5
16:28:16     INFO -  parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
16:28:16     INFO -  getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
16:28:16     INFO -  EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
16:28:16     INFO -      hookup@SimpleTest/setup.js:246:5
16:28:16     INFO -  EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1523980557%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
16:28:17     INFO -  Not taking screenshot here: see the one that was previously logged
16:28:17    ERROR -  1405 INFO TEST-UNEXPECTED-FAIL | toolkit/components/payments/test/mochitest/test_payment_dialog.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
16:28:17     INFO -      SimpleTest.is@SimpleTest/SimpleTest.js:312:5
16:28:17     INFO -      afterCleanup@SimpleTest/SimpleTest.js:1179:13
16:28:17     INFO -      executeCleanupFunction@SimpleTest/SimpleTest.js:1221:13
16:28:17     INFO -      SimpleTest.finish@SimpleTest/SimpleTest.js:1240:5
16:28:17     INFO -      killTest@SimpleTest/TestRunner.js:130:7
16:28:17     INFO -      delayedKillTest@SimpleTest/TestRunner.js:157:47
16:28:17     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:157:7
16:28:17     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
16:28:17     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
16:28:17     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
16:28:17     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
16:28:17     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
16:28:17     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
16:28:17     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
16:28:17     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
16:28:17     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
16:28:17     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
16:28:17     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
16:28:17     INFO -      TestRunner.runTests@SimpleTest/TestRunner.js:380:5
16:28:17     INFO -      RunSet.runtests@SimpleTest/setup.js:194:3
16:28:17     INFO -      RunSet.runall@SimpleTest/setup.js:173:5
16:28:17     INFO -      hookupTests@SimpleTest/setup.js:266:5
16:28:17     INFO -  parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
16:28:17     INFO -  getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
16:28:17     INFO -  EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
16:28:17     INFO -      hookup@SimpleTest/setup.js:246:5
16:28:17     INFO -  EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1523980557%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
16:28:17     INFO -  GECKO(5492) | MEMORY STAT | vsize 473MB | vsizeMaxContiguous 804MB | residentFast 87MB | heapAllocated 15MB
16:28:17     INFO -  1406 INFO TEST-OK | toolkit/components/payments/test/mochitest/test_payment_dialog.html | took 327956ms
16:28:17     INFO -  1407 ERROR /tests/toolkit/components/payments/test/mochitest/test_payment_dialog.html 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().
16:28:17     INFO -  1408 INFO TEST-START | toolkit/components/payments/test/mochitest/test_payment_method_picker.html

Comment 1

[Cosmin Sabou [:CosminS]]

https://treeherder.mozilla.org/#/jobs?repo=mozilla-inbound&fromchange=cd3905bcf1147c445895c86f0126dff86398e6a6&filter-searchStr=3c524127403a8539c0c8078304c0719ac62b9352&selectedJob=174127118

Comment 2

[Intermittent Failures Robot]

4 failures in 782 pushes (0.005 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-inbound: 2
* autoland: 2

Platform breakdown:
* windows7-32: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1454704&startday=2018-04-16&endday=2018-04-22&tree=trunk

Comment 3

[Intermittent Failures Robot]

2 failures in 551 pushes (0.004 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-central: 2

Platform breakdown:
* macosx64-nightly: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1454704&startday=2018-04-30&endday=2018-05-06&tree=trunk

Comment 4

[Intermittent Failures Robot]

1 failures in 660 pushes (0.002 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* osx-10-10: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1454704&startday=2018-05-07&endday=2018-05-13&tree=trunk

Comment 5

[Emma Humphries ☕️🎸🧞‍♀️✨ (she/they) [:emceeaich] (Pacific Time) use needinfo]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup