Closed Bug 934066 Opened 6 years ago Closed 6 years ago

Intermittent test_geolocation_reset_accuracy_wrap.js | test failed (with xpcshell return code: 0), see following log | false == true - See following stack | and the same in test_cookies_read.js and test_metrics_storage.js

Categories

(Core :: DOM: Geolocation, defect)

All
Windows 8
defect
Not set

Tracking

()

RESOLVED FIXED
mozilla28
Tracking Status
firefox26 --- fixed
firefox27 --- fixed
firefox28 --- fixed
firefox-esr24 --- unaffected
b2g-v1.2 --- fixed

People

(Reporter: KWierso, Assigned: jdm)

References

Details

(Keywords: intermittent-failure)

Attachments

(1 file)

https://tbpl.mozilla.org/php/getParsedLog.php?id=30006727&tree=Mozilla-Inbound
slave: t-w864-ix-092



19:22:52     INFO -  TEST-PASS | C:\slave\test\build\tests\xpcshell\tests\uriloader\exthandler\tests\unit\test_punycodeURIs.js | test passed (time: 1170.000ms)
19:22:55     INFO -  Retrying tests that failed when run in parallel.
19:22:55     INFO -  TEST-INFO | C:\slave\test\build\tests\xpcshell\tests\dom\plugins\test\unit\test_nice_plugin_name.js | running test ...
19:22:56     INFO -  TEST-PASS | C:\slave\test\build\tests\xpcshell\tests\dom\plugins\test\unit\test_nice_plugin_name.js | test passed (time: 338.000ms)
19:22:56     INFO -  TEST-INFO | C:\slave\test\build\tests\xpcshell\tests\dom\tests\unit\test_geolocation_reset_accuracy_wrap.js | running test ...
19:23:02  WARNING -  TEST-UNEXPECTED-FAIL | C:\slave\test\build\tests\xpcshell\tests\dom\tests\unit\test_geolocation_reset_accuracy_wrap.js | test failed (with xpcshell return code: 0), see following log:
19:23:02     INFO -  >>>>>>>
19:23:02     INFO -  TEST-INFO | (xpcshell/head.js) | test MAIN run_test pending (1)
19:23:02     INFO -  System JS : WARNING C:\slave\test\build\tests\xpcshell\head.js:1169 - assignment to undeclared variable _XPCSHELL_PROCESS
19:23:02     INFO -  [Parent 2616] WARNING: Couldn't get the user appdata directory, crash dumps will go in an unusual location: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/toolkit/crashreporter/nsExceptionHandler.cpp, line 1913
19:23:02     INFO -  parent: TEST-INFO | (xpcshell/head.js) | test run in child pending (2)
19:23:02     INFO -  parent: TEST-INFO | (xpcshell/head.js) | test MAIN run_test finished (2)
19:23:02     INFO -  parent: TEST-INFO | (xpcshell/head.js) | running event loop
19:23:02     INFO -  WARNING: XPCOM objects created/destroyed from static ctor/dtor: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/base/nsTraceRefcntImpl.cpp, line 138
19:23:02     INFO -  WARNING: XPCOM objects created/destroyed from static ctor/dtor: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/base/nsTraceRefcntImpl.cpp, line 138
19:23:02     INFO -  [Child 4744] WARNING: NS_ENSURE_TRUE(mMainThread) failed: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/threads/nsThreadManager.cpp, line 249
19:23:02     INFO -  [Child 4744] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0xC1F30001: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/glue/nsThreadUtils.cpp, line 174
19:23:02     INFO -  [Child 4744] WARNING: NS_ENSURE_TRUE(mMainThread) failed: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/threads/nsThreadManager.cpp, line 249
19:23:02     INFO -  [Child 4744] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0xC1F30001: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/glue/nsThreadUtils.cpp, line 174
19:23:02     INFO -  [Child 4744] WARNING: NS_ENSURE_TRUE(mMainThread) failed: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/threads/nsThreadManager.cpp, line 249
19:23:02     INFO -  [Child 4744] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0xC1F30001: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/glue/nsThreadUtils.cpp, line 174
19:23:02     INFO -  [Child 4744] WARNING: NS_ENSURE_TRUE(mMainThread) failed: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/threads/nsThreadManager.cpp, line 249
19:23:02     INFO -  [Child 4744] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0xC1F30001: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/glue/nsThreadUtils.cpp, line 174
19:23:02     INFO -  [Child 4744] WARNING: NS_ENSURE_TRUE(mMainThread) failed: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/threads/nsThreadManager.cpp, line 249
19:23:02     INFO -  [Child 4744] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0xC1F30001: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/glue/nsThreadUtils.cpp, line 174
19:23:02     INFO -  [Child 4744] WARNING: NS_ENSURE_TRUE(mMainThread) failed: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/threads/nsThreadManager.cpp, line 249
19:23:02     INFO -  [Child 4744] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0xC1F30001: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/glue/nsThreadUtils.cpp, line 174
19:23:02     INFO -  [Child 4744] WARNING: NS_ENSURE_TRUE(mMainThread) failed: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/threads/nsThreadManager.cpp, line 249
19:23:02     INFO -  [Child 4744] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0xC1F30001: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/glue/nsThreadUtils.cpp, line 174
19:23:02     INFO -  [Child 4744] WARNING: NS_ENSURE_TRUE(compMgr) failed: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/glue/nsComponentManagerUtils.cpp, line 58
19:23:02     INFO -  [Child 4744] WARNING: NS_ENSURE_TRUE(svc) failed: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/ipc/ContentChild.cpp, line 597
19:23:02     INFO -  [Child 4744] WARNING: NS_ENSURE_TRUE(compMgr) failed: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/glue/nsComponentManagerUtils.cpp, line 58
19:23:02     INFO -  child: CHILD-TEST-STARTED
19:23:02     INFO -  child: TEST-INFO | (xpcshell/head.js) | test MAIN run_test pending (1)
19:23:02     INFO -  child: TEST-INFO | (xpcshell/head.js) | test pending (2)
19:23:02     INFO -  child: TEST-INFO | (xpcshell/head.js) | test MAIN run_test finished (2)
19:23:02     INFO -  child: TEST-INFO | (xpcshell/head.js) | running event loop
19:23:02     INFO -  [Parent 2616] WARNING: No disk space watcher component available!: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/indexedDB/IndexedDatabaseManager.cpp, line 301
19:23:02     INFO -  child: TEST-INFO | (xpcshell/head.js) | test finished (1)
19:23:02     INFO -  child: TEST-INFO | (xpcshell/head.js) | exiting test
19:23:02     INFO -  child: TEST-INFO | (xpcshell/head.js) | No (+ 0) checks actually run
19:23:02     INFO -  child: CHILD-TEST-COMPLETED
19:23:02     INFO -  parent: TEST-UNEXPECTED-FAIL | C:/slave/test/build/tests/xpcshell/tests/dom/tests/unit/test_geolocation_reset_accuracy_wrap.js | false == true - See following stack:
19:23:02     INFO -  JS frame :: C:/slave/test/build/tests/xpcshell/tests/dom/tests/unit/test_geolocation_reset_accuracy_wrap.js :: check_results :: line 65
19:23:02     INFO -  native frame :: <unknown filename> :: <TOP_LEVEL> :: line 0
19:23:02     INFO -  parent: TEST-INFO | (xpcshell/head.js) | exiting test
19:23:02     INFO -  System JS : ERROR (null):0 - uncaught exception: 2147500036
19:23:02     INFO -  [Parent 2616] WARNING: NS_ENSURE_TRUE(ok) failed: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/ipc/testshell/TestShellParent.cpp, line 76
19:23:02     INFO -  NOTE: child process received `G[Parent 2616] WARNING: RemoveObserver() called for unregistered observer: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/hal/Hal.cpp, line 205
19:23:02     INFO -  oodbye', closing down
19:23:02     INFO -  [Parent 2616] WARNING: RemoveObserver() called for unregistered observer: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/hal/Hal.cpp, line 205
19:23:02     INFO -  [Parent 2616] WARNING: RemoveObserver() called for unregistered observer: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/hal/Hal.cpp, line 205
19:23:02     INFO -  [Parent 2616] WARNING: RemoveObserver() called for unregistered observer: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/hal/Hal.cpp, line 205
19:23:02     INFO -  [Parent 2616] WARNING: RemoveObserver() called for unregistered observer: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/hal/Hal.cpp, line 205
19:23:02     INFO -  [Child 4744] WARNING: pipe error: 109: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 312
19:23:02     INFO -  ###!!! [Child][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost
19:23:02     INFO -  [Child 4744] WARNING: content process _exit()ing: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/dom/ipc/ContentChild.cpp, line 1116
19:23:02     INFO -  nsStringStats
19:23:02     INFO -   => mAllocCount:           4527
19:23:02     INFO -   => mReallocCount:          199
19:23:02     INFO -   => mFreeCount:            2901  --  LEAKED 1626 !!!
19:23:02     INFO -   => mShareCount:           8438
19:23:02     INFO -   => mAdoptCount:            131
19:23:02     INFO -   => mAdoptFreeCount:        129  --  LEAKED 2 !!!
19:23:02     INFO -  [Parent 2616] WARNING: NS_ENSURE_TRUE(compMgr) failed: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/glue/nsComponentManagerUtils.cpp, line 58
19:23:02     INFO -  nsStringStats
19:23:02     INFO -   => mAllocCount:           4665
19:23:02     INFO -   => mReallocCount:          236
19:23:02     INFO -   => mFreeCount:            4665
19:23:02     INFO -   => mShareCount:           8703
19:23:02     INFO -   => mAdoptCount:            154
19:23:02     INFO -   => mAdoptFreeCount:        154
19:23:02     INFO -  <<<<<<<
19:23:05     INFO -  TEST-INFO | C:\slave\test\build\tests\xpcshell\tests\dom\tests\unit\test_geolocation_reset_accuracy.js | running test ...
19:23:12     INFO -  TEST-PASS | C:\slave\test\build\tests\xpcshell\tests\dom\tests\unit\test_geolocation_reset_accuracy.js | test passed (time: 6213.000ms)
19:23:12     INFO -  TEST-INFO | C:\slave\test\build\tests\xpcshell\tests\dom\indexedDB\test\unit\test_open_empty_db.js | running test ...
19:23:17     INFO -  TEST-PASS | C:\slave\test\build\tests\xpcshell\tests\dom\indexedDB\test\unit\test_open_empty_db.js | test passed (time: 4941.000ms)
19:23:17     INFO -  TEST-INFO | C:\slave\test\build\tests\xpcshell\tests\dom\indexedDB\test\unit\test_open_objectStore.js | running test ...
19:23:17     INFO -  TEST-PASS | C:\slave\test\build\tests\xpcshell\tests\dom\indexedDB\test\unit\test_open_objectStore.js | test passed (time: 536.000ms)
19:23:17     INFO -  TEST-INFO | C:\slave\test\build\tests\xpcshell\tests\dom\indexedDB\test\unit\test_overlapping_transactions.js | running test ...
19:27:14     INFO -  TEST-PASS | C:\slave\test\build\tests\xpcshell\tests\dom\indexedDB\test\unit\test_overlapping_transactions.js | test passed (time: 237109.000ms)
19:27:14     INFO -  TEST-INFO | C:\slave\test\build\tests\xpcshell\tests\extensions\cookie\test\unit\test_cookies_read.js | running test ...
19:32:14  WARNING -  TEST-UNEXPECTED-FAIL | C:\slave\test\build\tests\xpcshell\tests\extensions\cookie\test\unit\test_cookies_read.js | Test timed out
19:32:14     INFO -  Can't trigger Breakpad, just killing process
19:32:16  WARNING -  TEST-UNEXPECTED-FAIL | C:\slave\test\build\tests\xpcshell\tests\extensions\cookie\test\unit\test_cookies_read.js | test failed (with xpcshell return code: 15), see following log:
19:32:16     INFO -  >>>>>>>
19:32:16     INFO -  TEST-INFO | (xpcshell/head.js) | test MAIN run_test pending (1)
19:32:16     INFO -  TEST-INFO | (xpcshell/head.js) | test pending (2)
19:32:16     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/extensions/cookie/test/unit/test_cookies_read.js | [do_run_test : 33] true == true
19:32:16     INFO -  System JS : WARNING C:/slave/test/build/tests/xpcshell/tests/extensions/cookie/test/unit/test_cookies_read.js:48 - yield without a value is deprecated, and illegal in ES6 (use 'yield undefined' instead)
19:32:16     INFO -  System JS : WARNING C:/slave/test/build/tests/xpcshell/tests/extensions/cookie/test/unit/test_cookies_read.js:60 - yield without a value is deprecated, and illegal in ES6 (use 'yield undefined' instead)
19:32:16     INFO -  System JS : WARNING C:/slave/test/build/tests/xpcshell/tests/extensions/cookie/test/unit/test_cookies_read.js:81 - yield without a value is deprecated, and illegal in ES6 (use 'yield undefined' instead)
19:32:16     INFO -  System JS : WARNING C:/slave/test/build/tests/xpcshell/tests/extensions/cookie/test/unit/test_cookies_read.js:99 - yield without a value is deprecated, and illegal in ES6 (use 'yield undefined' instead)
19:32:16     INFO -  System JS : WARNING C:/slave/test/build/tests/xpcshell/tests/extensions/cookie/test/unit/test_cookies_read.js:107 - yield without a value is deprecated, and illegal in ES6 (use 'yield undefined' instead)
19:32:16     INFO -  System JS : WARNING C:/slave/test/build/tests/xpcshell/tests/extensions/cookie/test/unit/test_cookies_read.js:109 - yield without a value is deprecated, and illegal in ES6 (use 'yield undefined' instead)
19:32:16     INFO -  WARNING: NS_ENSURE_TRUE(aChannel) failed: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/content/base/src/ThirdPartyUtil.cpp, line 157
19:32:16     INFO -  pldhash: for the table at address 037B0828, the given entrySize of 72 definitely favors chaining over double hashing.
...
19:32:16     INFO -  <<<<<<<
19:32:16     INFO -  TEST-INFO | C:\slave\test\build\tests\xpcshell\tests\extensions\cookie\test\unit\test_cookies_sync_failure.js | running test ...
19:33:30     INFO -  TEST-PASS | C:\slave\test\build\tests\xpcshell\tests\extensions\cookie\test\unit\test_cookies_sync_failure.js | test passed (time: 73463.000ms)
19:33:30     INFO -  TEST-INFO | C:\slave\test\build\tests\xpcshell\tests\extensions\cookie\test\unit\test_cookies_thirdparty.js | running test ...
19:33:30     INFO -  TEST-PASS | C:\slave\test\build\tests\xpcshell\tests\extensions\cookie\test\unit\test_cookies_thirdparty.js | test passed (time: 206.000ms)
19:33:30     INFO -  TEST-INFO | C:\slave\test\build\tests\xpcshell\tests\netwerk\test\unit\test_bug651100.js | running test ...
19:33:56     INFO -  TEST-PASS | C:\slave\test\build\tests\xpcshell\tests\netwerk\test\unit\test_bug651100.js | test passed (time: 26238.000ms)
19:33:57     INFO -  TEST-INFO | C:\slave\test\build\tests\xpcshell\tests\services\healthreport\tests\xpcshell\test_healthreporter.js | running test ...
19:38:48     INFO -  TEST-PASS | C:\slave\test\build\tests\xpcshell\tests\services\healthreport\tests\xpcshell\test_healthreporter.js | test passed (time: 291599.000ms)
19:38:48     INFO -  TEST-INFO | C:\slave\test\build\tests\xpcshell\tests\services\healthreport\tests\xpcshell\test_provider_appinfo.js | running test ...
19:39:44     INFO -  TEST-PASS | C:\slave\test\build\tests\xpcshell\tests\services\healthreport\tests\xpcshell\test_provider_appinfo.js | test passed (time: 55266.000ms)
19:39:48     INFO -  TEST-INFO | C:\slave\test\build\tests\xpcshell\tests\services\healthreport\tests\xpcshell\test_provider_crashes.js | running test ...
19:39:57     INFO -  TEST-PASS | C:\slave\test\build\tests\xpcshell\tests\services\healthreport\tests\xpcshell\test_provider_crashes.js | test passed (time: 8838.000ms)
19:39:57     INFO -  TEST-INFO | C:\slave\test\build\tests\xpcshell\tests\services\metrics\tests\xpcshell\test_metrics_provider_manager.js | running test ...
19:42:14     INFO -  TEST-PASS | C:\slave\test\build\tests\xpcshell\tests\services\metrics\tests\xpcshell\test_metrics_provider_manager.js | test passed (time: 136867.000ms)
19:42:14     INFO -  TEST-INFO | C:\slave\test\build\tests\xpcshell\tests\services\metrics\tests\xpcshell\test_metrics_storage.js | running test ...
19:47:14  WARNING -  TEST-UNEXPECTED-FAIL | C:\slave\test\build\tests\xpcshell\tests\services\metrics\tests\xpcshell\test_metrics_storage.js | Test timed out
19:47:14     INFO -  Can't trigger Breakpad, just killing process
19:47:15  WARNING -  TEST-UNEXPECTED-FAIL | C:\slave\test\build\tests\xpcshell\tests\services\metrics\tests\xpcshell\test_metrics_storage.js | test failed (with xpcshell return code: 15), see following log:
19:47:15     INFO -  >>>>>>>
Summary: Intermittent test_geolocation_reset_accuracy_wrap.js | test failed (with xpcshell return code: 0), see following log | false == true - See following stack | test_cookies_read.js | Test timed out → Intermittent test_geolocation_reset_accuracy_wrap.js | test failed (with xpcshell return code: 0), see following log | false == true - See following stack | and the same in test_cookies_read.js and test_metrics_storage.js
Assignee: nobody → josh
Status: NEW → ASSIGNED
Ted, can you please give this a look? It's blocking another pretty frequent orange bug.
Flags: needinfo?(ted)
Comment on attachment 826294 [details] [diff] [review]
Add a simple parent/child synchronization mechanism for xpcshell tests.

Review of attachment 826294 [details] [diff] [review]:
-----------------------------------------------------------------

I don't really know the geolocation test well enough to judge your changes, but I'll trust your judgement. The xpcshell API seems fine.

::: testing/xpcshell/head.js
@@ +1213,5 @@
> +/**
> + * Execute a given function as soon as a particular cross-process message is received.
> + * Must be paired with do_send_remote_message or equivalent ProcessMessageManager calls.
> + */
> +function do_await_remote_message(name, callback)

Can you put a simple test for this in testing/xpcshell/example?

@@ +1249,5 @@
> +  } else {
> +    mm = Cc["@mozilla.org/childprocessmessagemanager;1"].getService(Ci.nsISyncMessageSender);
> +    sender = 'sendAsyncMessage';
> +  }
> +  mm[sender](name);

Doesn't feel like using the intermediate sender variable makes this any clearer. I'd probably just call the methods directly in the branches of the if.
Attachment #826294 - Flags: review?(ted) → review+
Flags: needinfo?(ted)
https://hg.mozilla.org/mozilla-central/rev/43c41041fe37
Status: ASSIGNED → RESOLVED
Closed: 6 years ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla28
Josh, I went ahead and pushed this due to the frequency of bug 931930. I guess you'll want to make a follow-up patch for the review comments.
Blocks: 931930
Flags: needinfo?(josh)
Finally got around to filing bug 1078894 to follow up.
Flags: needinfo?(josh)
You need to log in before you can comment on or make changes to this bug.