Open Bug 1454905 Opened 4 years ago Updated 3 years ago

Intermittent browser/components/payments/test/mochitest/test_payer_address_picker.html | Test timed out.

Categories

(Firefox :: WebPayments UI, defect, P5)

defect

Tracking

()

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, Whiteboard: [webpayments-reserve][retriggered])

Filed by: ccoroiu [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=174280637&repo=autoland

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

09:14:05     INFO -  1936 INFO None1937 INFO TEST-START | toolkit/components/payments/test/mochitest/test_payer_address_picker.html
09:14:05     INFO -  GECKO(5700) | ++DOMWINDOW == 17 (0B87A800) [pid = 2816] [serial = 22] [outer = 0B829EC0]
09:14:05     INFO -  GECKO(5700) | ++DOCSHELL 0A1EF400 == 3 [pid = 2816] [id = {7138adea-0b7c-4d52-ae1e-5d81ef2bbf78}]
09:14:05     INFO -  GECKO(5700) | ++DOMWINDOW == 18 (00A53300) [pid = 2816] [serial = 23] [outer = 00000000]
09:14:05     INFO -  GECKO(5700) | ++DOMWINDOW == 19 (0CD84C00) [pid = 2816] [serial = 24] [outer = 00A53300]
09:14:05     INFO -  GECKO(5700) | ++DOCSHELL 0CD87C00 == 4 [pid = 2816] [id = {007af3f5-8c60-4ec8-b9c9-4e9108a0e1d4}]
09:14:05     INFO -  GECKO(5700) | ++DOMWINDOW == 20 (0B82DEC0) [pid = 2816] [serial = 25] [outer = 00000000]
09:14:05     INFO -  GECKO(5700) | ++DOMWINDOW == 21 (0CA4CC00) [pid = 2816] [serial = 26] [outer = 0B82DEC0]
09:14:06     INFO -  GECKO(5700) | [Child 2816, Main Thread] WARNING: Subsequent listeners are not retargetable: file z:/build/build/src/netwerk/protocol/http/HttpChannelChild.cpp, line 3565
09:14:06     INFO -  GECKO(5700) | [Child 2816, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file z:/build/build/src/parser/html/nsHtml5StreamParser.cpp, line 1003
09:14:08     INFO -  GECKO(5700) | --DOMWINDOW == 2 (05A3A400) [pid = 5760] [serial = 2] [outer = 00000000] [url = about:blank]
09:14:09     INFO -  GECKO(5700) | --DOMWINDOW == 10 (0ED84000) [pid = 5700] [serial = 4] [outer = 00000000] [url = about:blank]
09:14:09     INFO -  GECKO(5700) | --DOMWINDOW == 9 (11D48480) [pid = 5700] [serial = 9] [outer = 00000000] [url = about:blank]
09:14:11     INFO -  GECKO(5700) | --DOMWINDOW == 1 (012531C0) [pid = 5760] [serial = 1] [outer = 00000000] [url = chrome://gfxsanity/content/sanitytest.html]
09:14:12     INFO -  GECKO(5700) | [Parent 5700, StreamTrans #13] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/modules/libjar/nsJARChannel.cpp, line 417
09:14:13     INFO -  GECKO(5700) | --DOMWINDOW == 0 (07BE5800) [pid = 5760] [serial = 3] [outer = 00000000] [url = chrome://gfxsanity/content/sanitytest.html]
09:14:13     INFO -  GECKO(5700) | --DOMWINDOW == 20 (0553CC00) [pid = 2816] [serial = 8] [outer = 00000000] [url = http://mochi.test:8888/tests/toolkit/components/payments/test/mochitest/test_ObservedPropertiesMixin.html]
09:14:13     INFO -  GECKO(5700) | --DOMWINDOW == 19 (0CA43C00) [pid = 2816] [serial = 11] [outer = 00000000] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html]
09:14:13     INFO -  GECKO(5700) | --DOMWINDOW == 18 (0CA41400) [pid = 2816] [serial = 12] [outer = 00000000] [url = http://mochi.test:8888/tests/toolkit/components/payments/test/mochitest/test_PaymentsStore.html]
09:14:13     INFO -  GECKO(5700) | --DOMWINDOW == 17 (0CA45800) [pid = 2816] [serial = 13] [outer = 00000000] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html]
09:14:13     INFO -  GECKO(5700) | --DOMWINDOW == 16 (0CA44800) [pid = 2816] [serial = 14] [outer = 00000000] [url = http://mochi.test:8888/tests/toolkit/components/payments/test/mochitest/test_address_picker.html]
09:14:13     INFO -  GECKO(5700) | --DOMWINDOW == 15 (0CA48400) [pid = 2816] [serial = 15] [outer = 00000000] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html]
09:14:13     INFO -  GECKO(5700) | --DOMWINDOW == 14 (0CA46C00) [pid = 2816] [serial = 16] [outer = 00000000] [url = http://mochi.test:8888/tests/toolkit/components/payments/test/mochitest/test_basic_card_form.html]
09:14:13     INFO -  GECKO(5700) | --DOMWINDOW == 13 (0A1F2400) [pid = 2816] [serial = 10] [outer = 00000000] [url = http://mochi.test:8888/tests/toolkit/components/payments/test/mochitest/test_PaymentStateSubscriberMixin.html]
09:14:17     INFO -  GECKO(5700) | --DOMWINDOW == 8 (13EDE000) [pid = 5700] [serial = 10] [outer = 00000000] [url = about:blank]
09:14:18     INFO -  GECKO(5700) | --DOMWINDOW == 12 (0CA42800) [pid = 2816] [serial = 18] [outer = 00000000] [url = http://mochi.test:8888/tests/toolkit/components/payments/test/mochitest/test_currency_amount.html]
09:14:18     INFO -  GECKO(5700) | --DOMWINDOW == 11 (0CD7D400) [pid = 2816] [serial = 20] [outer = 00000000] [url = http://mochi.test:8888/tests/toolkit/components/payments/test/mochitest/test_order_details.html]
09:14:18     INFO -  GECKO(5700) | --DOMWINDOW == 10 (0A1EFC00) [pid = 2816] [serial = 17] [outer = 00000000] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html]
09:14:18     INFO -  GECKO(5700) | --DOMWINDOW == 9 (0A1F4000) [pid = 2816] [serial = 21] [outer = 00000000] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html]
09:14:18     INFO -  GECKO(5700) | --DOMWINDOW == 8 (0CD81800) [pid = 2816] [serial = 19] [outer = 00000000] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html]
09:15:55     INFO -  GECKO(5700) | [Parent 5700, Lazy Idle] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/widget/windows/WinUtils.cpp, line 1469
09:17:55     INFO -  GECKO(5700) | [Parent 5700, Lazy Idle] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/widget/windows/WinUtils.cpp, line 1469
09:19:26     INFO -  TEST-INFO | started process screenshot
09:19:26     INFO -  TEST-INFO | screenshot: exit 0
09:19:26     INFO -  Buffered messages logged at 09:14:05
09:19:26     INFO -  1938 INFO SpawnTask.js | Entering test setup_once
09:19:26     INFO -  1939 INFO must wait for load
09:19:26     INFO -  1940 INFO must wait for focus
09:19:26     INFO -  Buffered messages finished
09:19:26    ERROR -  1941 INFO TEST-UNEXPECTED-FAIL | toolkit/components/payments/test/mochitest/test_payer_address_picker.html | Test timed out.
09:19:26     INFO -      reportError@SimpleTest/TestRunner.js:121:7
09:19:26     INFO -      TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
09:19:26     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
09:19:26     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
09:19:26     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
09:19:26     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
09:19:26     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
09:19:26     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
09:19:26     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
09:19:26     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
09:19:26     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
09:19:26     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
09:19:26     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
09:19:26     INFO -      TestRunner.runTests@SimpleTest/TestRunner.js:380:5
09:19:26     INFO -      RunSet.runtests@SimpleTest/setup.js:194:3
09:19:26     INFO -      RunSet.runall@SimpleTest/setup.js:173:5
09:19:26     INFO -      hookupTests@SimpleTest/setup.js:266:5
09:19:26     INFO -  parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
09:19:26     INFO -  getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
09:19:26     INFO -  EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
09:19:26     INFO -      hookup@SimpleTest/setup.js:246:5
09:19:26     INFO -  EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1524039446%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
09:19:27     INFO -  Not taking screenshot here: see the one that was previously logged
09:19:27    ERROR -  1942 INFO TEST-UNEXPECTED-FAIL | toolkit/components/payments/test/mochitest/test_payer_address_picker.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
09:19:27     INFO -      SimpleTest.is@SimpleTest/SimpleTest.js:312:5
Whiteboard: [webpayments] [triage]
Product: Toolkit → Firefox
Version: unspecified → Trunk
Priority: P5 → P3
Whiteboard: [webpayments] [triage] → [webpayments-reserve]
Summary: Intermittent toolkit/components/payments/test/mochitest/test_payer_address_picker.html | Test timed out. → Intermittent browser/components/payments/test/mochitest/test_payer_address_picker.html | Test timed out.
thanks :ebalazs, this looks right on.  :mattn, I see you authored the patches in bug 1435163, can you look at this intermittent that appears to be greatly increased in frequency by that change?
Blocks: 1435163
Flags: needinfo?(jmaher) → needinfo?(MattN+bmo)
This seems to have fixed itself.
Flags: needinfo?(MattN+bmo)
Priority: P3 → P5
You need to log in before you can comment on or make changes to this bug.