1572575 - Intermittent (SimpleTest/TestRunner.js) | /tests/dom/tests/mochitest/whatwg/test_postMessage_userpass.html - finished in a non-clean fashion, probably because it didn't call SimpleTest.finish()

Reporter

Description

•

5 years ago

treeherder

Filed by: rmaries [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=260655734&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/dSLbEkAJTtWZ3TQpiS1EJg/runs/0/artifacts/public/logs/live_backing.log

[task 2019-08-08T21:29:20.645Z] 21:29:20 [task 2019-08-08T21:34:51.140Z] 21:34:51 [task 2019-08-08T21:34:51.140Z] 21:34:51 [task 2019-08-08T21:34:51.140Z] 21:34:51 [task 2019-08-08T21:34:51.140Z] 21:34:51 [task 2019-08-08T21:34:51.140Z] 21:34:51 [task 2019-08-08T21:34:51.140Z] 21:34:51 [task 2019-08-08T21:34:51.149Z] 21:34:51 [task 2019-08-08T21:34:51.149Z] 21:34:51 [task 2019-08-08T21:34:51.150Z] 21:34:51 [task 2019-08-08T21:34:51.151Z] 21:34:51 [task 2019-08-08T21:34:51.152Z] 21:34:51 [task 2019-08-08T21:34:51.153Z] 21:34:51 [task 2019-08-08T21:34:51.153Z] 21:34:51 [task 2019-08-08T21:34:51.154Z] 21:34:51 [task 2019-08-08T21:34:51.155Z] 21:34:51 [task 2019-08-08T21:34:51.155Z] 21:34:51 [task 2019-08-08T21:34:51.272Z] 21:34:51 [task 2019-08-08T21:34:51.353Z] 21:34:51 [task 2019-08-08T21:34:51.354Z] 21:34:51 [task 2019-08-08T21:34:51.369Z] 21:34:51 [task 2019-08-08T21:34:51.377Z] 21:34:51 [task 2019-08-08T21:34:51.377Z] 21:34:51 [task 2019-08-08T21:34:51.414Z] 21:34:51 [task 2019-08-08T21:34:51.414Z] 21:34:51 [task 2019-08-08T21:34:51.414Z] 21:34:51 [task 2019-08-08T21:34:51.414Z] 21:34:51 [task 2019-08-08T21:34:51.414Z] 21:34:51 [task 2019-08-08T21:34:51.415Z] 21:34:51 [task 2019-08-08T21:34:51.415Z] 21:34:51 [task 2019-08-08T21:34:51.418Z] 21:34:51 [task 2019-08-08T21:34:51.419Z] 21:34:51 [task 2019-08-08T21:34:51.419Z] 21:34:51 [task 2019-08-08T21:34:51.427Z] 21:34:51 [task 2019-08-08T21:34:51.428Z] 21:34:51 [task 2019-08-08T21:34:51.428Z] 21:34:51 [task 2019-08-08T21:34:51.428Z] 21:34:51 [task 2019-08-08T21:34:51.511Z] 21:34:51 [task 2019-08-08T21:34:51.513Z] 21:34:51 [task 2019-08-08T21:34:51.513Z] 21:34:51 [task 2019-08-08T21:34:51.514Z] 21:34:51 [task 2019-08-08T21:34:51.515Z] 21:34:51 [task 2019-08-08T21:34:51.516Z] 21:34:51 [task 2019-08-08T21:34:51.517Z] 21:34:51 [task 2019-08-08T21:34:51.517Z] 21:34:51 [task 2019-08-08T21:34:51.559Z] 21:34:51 [task 2019-08-08T21:34:51.559Z] 21:34:51 [task 2019-08-08T21:34:51.559Z] 21:34:51 [task 2019-08-08T21:34:51.559Z] 21:34:51 [task 2019-08-08T21:34:51.559Z] 21:34:51 [task 2019-08-08T21:34:51.559Z] 21:34:51 [task 2019-08-08T21:34:51.559Z] 21:34:51 [task 2019-08-08T21:34:51.560Z] 21:34:51 [task 2019-08-08T21:34:51.560Z] 21:34:51 [task 2019-08-08T21:34:51.561Z] 21:34:51 [task 2019-08-08T21:34:51.800Z] 21:34:51 [task 2019-08-08T21:34:51.801Z] 21:34:51 [task 2019-08-08T21:34:51.801Z] 21:34:51 [task 2019-08-08T21:34:51.801Z] 21:34:51 [task 2019-08-08T21:34:51.801Z] 21:34:51 [task 2019-08-08T21:34:51.877Z] 21:34:51 [task 2019-08-08T21:34:51.877Z] 21:34:51 [task 2019-08-08T21:34:51.877Z] 21:34:51 [task 2019-08-08T21:34:51.878Z] 21:34:51 [task 2019-08-08T21:34:51.879Z] 21:34:51 [task 2019-08-08T21:34:51.880Z] 21:34:51 [task 2019-08-08T21:34:51.880Z] 21:34:51 [task 2019-08-08T21:34:51.881Z] 21:34:51 [task 2019-08-08T21:34:51.957Z] 21:34:51 [task 2019-08-08T21:34:51.958Z] 21:34:51 [task 2019-08-08T21:34:52.066Z] 21:34:52 [task 2019-08-08T21:34:52.067Z] 21:34:52 [task 2019-08-08T21:34:52.067Z] 21:34:52 [task 2019-08-08T21:34:52.067Z] 21:34:52 [task 2019-08-08T21:34:52.067Z] 21:34:52 [task 2019-08-08T21:34:52.070Z] 21:34:52 [task 2019-08-08T21:34:52.139Z] 21:34:52 [task 2019-08-08T21:34:52.139Z] 21:34:52 [task 2019-08-08T21:34:52.139Z] 21:34:52 [task 2019-08-08T21:34:52.139Z] 21:34:52 [task 2019-08-08T21:34:52.140Z] 21:34:52 [task 2019-08-08T21:34:52.140Z] 21:34:52 [task 2019-08-08T21:34:52.140Z] 21:34:52 [task 2019-08-08T21:34:52.140Z] 21:34:52 [task 2019-08-08T21:34:52.140Z] 21:34:52 [task 2019-08-08T21:34:52.140Z] 21:34:52 [task 2019-08-08T21:34:52.199Z] 21:34:52 [task 2019-08-08T21:34:52.200Z] 21:34:52 [task 2019-08-08T21:34:52.200Z] 21:34:52 [task 2019-08-08T21:34:52.241Z] 21:34:52 [task 2019-08-08T21:34:52.241Z] 21:34:52 [task 2019-08-08T21:34:52.242Z] 21:34:52 [task 2019-08-08T21:34:52.243Z] 21:34:52 [task 2019-08-08T21:34:52.243Z] 21:34:52 [task 2019-08-08T21:34:52.244Z] 21:34:52 [task 2019-08-08T21:34:52.244Z] 21:34:52 [task 2019-08-08T21:34:52.245Z] 21:34:52 [task 2019-08-08T21:34:52.245Z] 21:34:52 [task 2019-08-08T21:34:52.246Z] 21:34:52 [task 2019-08-08T21:34:52.249Z] 21:34:52 [task 2019-08-08T21:34:52.251Z] 21:34:52 [task 2019-08-08T21:34:52.252Z] 21:34:52 [task 2019-08-08T21:34:52.261Z] 21:34:52 [task 2019-08-08T21:34:53.008Z] 21:34:53 [task 2019-08-08T21:34:53.011Z] 21:34:53 [task 2019-08-08T21:34:53.012Z] 21:34:53 [task 2019-08-08T21:34:53.012Z] 21:34:53 [task 2019-08-08T21:34:53.012Z] 21:34:53 [task 2019-08-08T21:34:53.016Z] 21:34:53 [task 2019-08-08T21:34:53.016Z] 21:34:53 [task 2019-08-08T21:34:53.017Z] 21:34:53 [task 2019-08-08T21:34:53.024Z] 21:34:53 [task 2019-08-08T21:34:53.024Z] 21:34:53 [task 2019-08-08T21:34:53.072Z] 21:34:53 [task 2019-08-08T21:34:53.072Z] 21:34:53 [task 2019-08-08T21:34:53.073Z] 21:34:53 [task 2019-08-08T21:34:53.073Z] 21:34:53 [task 2019-08-08T21:34:53.074Z] 21:34:53 [task 2019-08-08T21:34:53.074Z] 21:34:53 [task 2019-08-08T21:34:53.074Z] 21:34:53 [task 2019-08-08T21:34:53.075Z] 21:34:53 [task 2019-08-08T21:34:53.075Z] 21:34:53 [task 2019-08-08T21:34:53.075Z] 21:34:53 INFO - TEST-START | dom/tests/mochitest/whatwg/test_postMessage_userpass.html
INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | /tests/dom/tests/mochitest/whatwg/test_postMessage_userpass.html - finished in a non-clean fashion, probably because it didn't call SimpleTest.finish()
INFO - {u'loaded_test_url': u'/tests/dom/tests/mochitest/whatwg/test_postMessage_userpass.html'}
INFO - TEST-UNEXPECTED-ERROR | (SimpleTest/TestRunner.js) | Finished in 329970ms
INFO - {u'runtime': 329970}
INFO - TEST-INFO
INFO - GECKO(2637) | ++DOMWINDOW == 5 (0x7f0845d25400) [pid = 2811] [serial = 80] [outer = 0x7f0849a6c2e0]
INFO - TEST-START | Shutdown
INFO - Passed: 264
INFO - Failed: 1
INFO - Todo: 4
INFO - Mode: e10s
INFO - Slowest: 329970ms - (SimpleTest/TestRunner.js)
INFO - SimpleTest FINISHED
INFO - TEST-INFO | Ran 1 Loops
INFO - SimpleTest FINISHED
INFO - GECKO(2637) | ++DOMWINDOW == 6 (0x7f0845d29400) [pid = 2811] [serial = 81] [outer = 0x7f0849a6c2e0]
INFO - GECKO(2637) | --DOCSHELL 0x7f263f2ba000 == 0 [pid = 3144] [id = {0386e796-3308-4510-8afb-4b8201b8fd5c}] [url = http://bobhope:password@example.org/tests/dom/tests/mochitest/whatwg/postMessage_userpass_helper.html]
INFO - GECKO(2637) | --DOMWINDOW == 1 (0x7f263f294d40) [pid = 3144] [serial = 1] [outer = (nil)] [url = http://bobhope:password@example.org/tests/dom/tests/mochitest/whatwg/postMessage_userpass_helper.html]
INFO - GECKO(2637) | --DOMWINDOW == 0 (0x7f263f224000) [pid = 3144] [serial = 2] [outer = (nil)] [url = http://bobhope:password@example.org/tests/dom/tests/mochitest/whatwg/postMessage_userpass_helper.html]
INFO - GECKO(2637) | [Child 3144, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3361
INFO - GECKO(2637) | WARN 2019-08-08T21:34:51Z: xulstore::persist: tried to remove key that isn't in the store
INFO - GECKO(2637) | WARN 2019-08-08T21:34:51Z: xulstore::persist: tried to remove key that isn't in the store
INFO - GECKO(2637) | [Child 3144, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
INFO - GECKO(2637) | [Child 3144, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
INFO - GECKO(2637) | nsStringStats
INFO - GECKO(2637) | => mAllocCount: 6483
INFO - GECKO(2637) | => mReallocCount: 0
INFO - GECKO(2637) | => mFreeCount: 6483
INFO - GECKO(2637) | => mShareCount: 3843
INFO - GECKO(2637) | => mAdoptCount: 201
INFO - GECKO(2637) | => mAdoptFreeCount: 205
INFO - GECKO(2637) | => Process ID: 3144, Thread ID: 139802688485184
INFO - GECKO(2637) | --DOCSHELL 0x7f1e0c7bc000 == 3 [pid = 2686] [id = {90042bf2-2440-47a4-a6fc-7787a0639fb5}] [url = moz-extension://b6d5f7c1-4450-450e-92e4-189a6dba8471/_generated_background_page.html]
INFO - GECKO(2637) | --DOCSHELL 0x7f1e0c81d800 == 2 [pid = 2686] [id = {ed713ca1-6d59-4d55-a774-907f2548941f}] [url = moz-extension://a7a025ba-c4af-4392-9c82-9206565594ea/_generated_background_page.html]
INFO - GECKO(2637) | --DOCSHELL 0x7f1e0c81f800 == 1 [pid = 2686] [id = {04cc3b31-65cf-49d4-a66f-dacb2f68274c}] [url = moz-extension://114dc955-40d6-4dc9-b664-fe9ff6eeda36/_generated_background_page.html]
INFO - GECKO(2637) | --DOCSHELL 0x7f1e25883800 == 0 [pid = 2686] [id = {0d10a77a-55b5-4251-bdc6-f0bc46168f49}] [url = moz-extension://94302c51-c8e6-4fcd-a76c-4b7af0e35e7d/_generated_background_page.html]
INFO - GECKO(2637) | --DOMWINDOW == 7 (0x7f1e0c623d40) [pid = 2686] [serial = 1] [outer = (nil)] [url = moz-extension://b6d5f7c1-4450-450e-92e4-189a6dba8471/_generated_background_page.html]
INFO - GECKO(2637) | --DOMWINDOW == 6 (0x7f1e0c624100) [pid = 2686] [serial = 2] [outer = (nil)] [url = moz-extension://a7a025ba-c4af-4392-9c82-9206565594ea/_generated_background_page.html]
INFO - GECKO(2637) | --DOMWINDOW == 5 (0x7f1e0c6242e0) [pid = 2686] [serial = 3] [outer = (nil)] [url = moz-extension://114dc955-40d6-4dc9-b664-fe9ff6eeda36/_generated_background_page.html]
INFO - GECKO(2637) | --DOMWINDOW == 4 (0x7f1e0c6244c0) [pid = 2686] [serial = 4] [outer = (nil)] [url = moz-extension://94302c51-c8e6-4fcd-a76c-4b7af0e35e7d/_generated_background_page.html]
INFO - GECKO(2637) | --DOMWINDOW == 3 (0x7f1e0d87bc00) [pid = 2686] [serial = 14] [outer = (nil)] [url = moz-extension://94302c51-c8e6-4fcd-a76c-4b7af0e35e7d/_generated_background_page.html]
INFO - GECKO(2637) | --DOMWINDOW == 2 (0x7f1e0d87a800) [pid = 2686] [serial = 13] [outer = (nil)] [url = moz-extension://114dc955-40d6-4dc9-b664-fe9ff6eeda36/_generated_background_page.html]
INFO - GECKO(2637) | --DOMWINDOW == 1 (0x7f1e0d7b0400) [pid = 2686] [serial = 12] [outer = (nil)] [url = moz-extension://a7a025ba-c4af-4392-9c82-9206565594ea/_generated_background_page.html]
INFO - GECKO(2637) | --DOMWINDOW == 0 (0x7f1e0c8ad800) [pid = 2686] [serial = 11] [outer = (nil)] [url = moz-extension://b6d5f7c1-4450-450e-92e4-189a6dba8471/_generated_background_page.html]
INFO - GECKO(2637) | [Child 2686, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
INFO - GECKO(2637) | [Child 2686, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
INFO - GECKO(2637) | nsStringStats
INFO - GECKO(2637) | => mAllocCount: 17292
INFO - GECKO(2637) | => mReallocCount: 0
INFO - GECKO(2637) | => mFreeCount: 17292
INFO - GECKO(2637) | => mShareCount: 10883
INFO - GECKO(2637) | => mAdoptCount: 504
INFO - GECKO(2637) | => mAdoptFreeCount: 534
INFO - GECKO(2637) | => Process ID: 2686, Thread ID: 139767478249280
INFO - GECKO(2637) | --DOCSHELL 0x7f1f24409000 == 4 [pid = 2637] [id = {d9241913-59af-4872-892d-c45732cfbc14}] [url = resource://gre-resources/hiddenWindow.html]
INFO - GECKO(2637) | --DOCSHELL 0x7f1f2ba8e000 == 3 [pid = 2637] [id = {fb941243-f188-4668-ab73-a6ed718f0669}] [url = chrome://browser/content/browser.xhtml]
INFO - GECKO(2637) | --DOCSHELL 0x7f1f281be000 == 2 [pid = 2637] [id = {9a9196e0-f8a6-4964-a268-83a3539541c8}] [url = chrome://extensions/content/dummy.xul]
INFO - GECKO(2637) | --DOCSHELL 0x7f1f2874c000 == 1 [pid = 2637] [id = {88c40401-53ff-433f-8d85-1276ad53cf8d}] [url = about:blank]
INFO - GECKO(2637) | --DOCSHELL 0x7f1f28f18000 == 0 [pid = 2637] [id = {f8562fa2-1c71-4e97-bb27-7a45318b868b}] [url = about:blank]
INFO - GECKO(2637) | nsStringStats
INFO - GECKO(2637) | => mAllocCount: 6347
INFO - GECKO(2637) | => mReallocCount: 0
INFO - GECKO(2637) | => mFreeCount: 6347
INFO - GECKO(2637) | => mShareCount: 3427
INFO - GECKO(2637) | => mAdoptCount: 70
INFO - GECKO(2637) | => mAdoptFreeCount: 70
INFO - GECKO(2637) | => Process ID: 2764, Thread ID: 140547968874304
INFO - GECKO(2637) | --DOCSHELL 0x7f0848670800 == 1 [pid = 2811] [id = {a428e935-ca7d-4efd-90e7-d86a636d575e}] [url = about:blank]
INFO - GECKO(2637) | --DOCSHELL 0x7f0848bb7800 == 0 [pid = 2811] [id = {03954a02-65db-49d1-86d4-28eeb91622da}] [url = http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true]
INFO - GECKO(2637) | --DOMWINDOW == 5 (0x7f0849a6bd40) [pid = 2811] [serial = 1] [outer = (nil)] [url = http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true]
INFO - GECKO(2637) | --DOMWINDOW == 4 (0x7f0845d27c00) [pid = 2811] [serial = 78] [outer = (nil)] [url = http://mochi.test:8888/tests/dom/tests/mochitest/whatwg/test_postMessage_userpass.html]
INFO - GECKO(2637) | --DOMWINDOW == 3 (0x7f0845d25400) [pid = 2811] [serial = 80] [outer = (nil)] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html]
INFO - GECKO(2637) | --DOMWINDOW == 2 (0x7f0845d29400) [pid = 2811] [serial = 81] [outer = (nil)] [url = about:blank]
INFO - GECKO(2637) | --DOMWINDOW == 1 (0x7f0849a6c2e0) [pid = 2811] [serial = 4] [outer = (nil)] [url = about:blank]
INFO - GECKO(2637) | --DOMWINDOW == 0 (0x7f0849aa1000) [pid = 2811] [serial = 3] [outer = (nil)] [url = http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true]
INFO - GECKO(2637) | [Child 2811, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
INFO - GECKO(2637) | [Child 2811, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
INFO - GECKO(2637) | nsStringStats
INFO - GECKO(2637) | => mAllocCount: 38630
INFO - GECKO(2637) | => mReallocCount: 0
INFO - GECKO(2637) | => mFreeCount: 38630
INFO - GECKO(2637) | => mShareCount: 76775
INFO - GECKO(2637) | => mAdoptCount: 2237
INFO - GECKO(2637) | => mAdoptFreeCount: 2487
INFO - GECKO(2637) | => Process ID: 2811, Thread ID: 139673999275840
INFO - GECKO(2637) | --DOCSHELL 0x7f4febdb6800 == 0 [pid = 2787] [id = {b3f80e7d-f5c7-43f5-af96-f8a531c56d4c}] [url = about:newtab]
INFO - GECKO(2637) | --DOMWINDOW == 1 (0x7f4febd8cd40) [pid = 2787] [serial = 1] [outer = (nil)] [url = about:newtab]
INFO - GECKO(2637) | --DOMWINDOW == 0 (0x7f4fecc32400) [pid = 2787] [serial = 3] [outer = (nil)] [url = about:newtab]
INFO - GECKO(2637) | [Child 2787, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
INFO - GECKO(2637) | [Child 2787, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
INFO - GECKO(2637) | nsStringStats
INFO - GECKO(2637) | => mAllocCount: 12233
INFO - GECKO(2637) | => mReallocCount: 0
INFO - GECKO(2637) | => mFreeCount: 12233
INFO - GECKO(2637) | => mShareCount: 15879
INFO - GECKO(2637) | => mAdoptCount: 542
INFO - GECKO(2637) | => mAdoptFreeCount: 546
INFO - GECKO(2637) | => Process ID: 2787, Thread ID: 139981678593856
INFO - GECKO(2637) | 1565300092246 Marionette TRACE Received observer notification xpcom-will-shutdown
INFO - GECKO(2637) | 1565300092247 Marionette INFO Stopped listening on port 2828
INFO - GECKO(2637) | 1565300092247 Marionette DEBUG Remote service is inactive
INFO - GECKO(2637) | [Parent 2637, Main Thread] WARNING: NS_ENSURE_TRUE(mDB) failed: file /builds/worker/workspace/build/src/netwerk/cache/nsDiskCacheDeviceSQL.cpp, line 1285
INFO - GECKO(2637) | --DOMWINDOW == 9 (0x7f1f2ff7a6a0) [pid = 2637] [serial = 1] [outer = (nil)] [url = chrome://browser/content/browser.xhtml]
INFO - GECKO(2637) | --DOMWINDOW == 8 (0x7f1f26c60c00) [pid = 2637] [serial = 8] [outer = (nil)] [url = about:blank]
INFO - GECKO(2637) | --DOMWINDOW == 7 (0x7f1f26c61800) [pid = 2637] [serial = 9] [outer = (nil)] [url = about:blank]
INFO - GECKO(2637) | --DOMWINDOW == 6 (0x7f1f288555c0) [pid = 2637] [serial = 5] [outer = (nil)] [url = chrome://extensions/content/dummy.xul]
INFO - GECKO(2637) | --DOMWINDOW == 5 (0x7f1f284d5c00) [pid = 2637] [serial = 7] [outer = (nil)] [url = chrome://extensions/content/dummy.xul]
INFO - GECKO(2637) | --DOMWINDOW == 4 (0x7f1f255e83e0) [pid = 2637] [serial = 10] [outer = (nil)] [url = resource://gre-resources/hiddenWindow.html]
INFO - GECKO(2637) | --DOMWINDOW == 3 (0x7f1f28855200) [pid = 2637] [serial = 4] [outer = (nil)] [url = about:blank]
INFO - GECKO(2637) | --DOMWINDOW == 2 (0x7f1f28855020) [pid = 2637] [serial = 3] [outer = (nil)] [url = about:blank]
INFO - GECKO(2637) | --DOMWINDOW == 1 (0x7f1f29811000) [pid = 2637] [serial = 2] [outer = (nil)] [url = about:blank]
INFO - GECKO(2637) | --DOMWINDOW == 0 (0x7f1f25567400) [pid = 2637] [serial = 12] [outer = (nil)] [url = resource://gre-resources/hiddenWindow.html]
INFO - GECKO(2637) | [Parent 2637, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
INFO - GECKO(2637) | [Parent 2637, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
INFO - GECKO(2637) | nsStringStats
INFO - GECKO(2637) | => mAllocCount: 176215
INFO - GECKO(2637) | => mReallocCount: 0
INFO - GECKO(2637) | => mFreeCount: 176215
INFO - GECKO(2637) | => mShareCount: 194466
INFO - GECKO(2637) | => mAdoptCount: 3395
INFO - GECKO(2637) | => mAdoptFreeCount: 4091
INFO - GECKO(2637) | => Process ID: 2637, Thread ID: 139772371011392

Comment hidden (Intermittent Failures Robot)

Alexandru Michis [:malexandru]

Comment 3

•

5 years ago

Joel, since the disable in https://bugzilla.mozilla.org/show_bug.cgi?id=1570918#c9 landed this has started appearing and failing quite frequently:

https://treeherder.mozilla.org/#/jobs?repo=mozilla-central&resultStatus=success%2Ctestfailed%2Cbusted%2Cexception%2Cusercancel%2Crunnable&searchStr=linux%2Cx64%2Cquantumrender%2Copt%2Cmochitests%2Cwith%2Cfission%2Cenabled%2Ctest-linux64-qr%2Fopt-mochitest-fis-e10s-4%2Cm-fis%284%29&tochange=c53f789ffabb03c04b1b20252aea9331301bfa02&fromchange=2909b0a1eb06cc34ce0a11544e5e6826aba87c06

Should we disable this as well?

Flags: needinfo?(jmaher)

Joel Maher ( :jmaher ) (UTC -8)

Comment 4

•

5 years ago

:malexandru, I am slightly confused here. The history for this failure shows it starting on August 7th, and since then it has been pretty frequent. I don't see signs of retrigges to help show a failure or pass rate. Looking at passing instances this test test_postMessage_userpass.html completes in 328 seconds, but it also fails with timeout in 328 seconds, I suspect this is close to timeout all the time.

Can you get retriggers and help show the failure/pass rate on a few days of mozilla-central pushes?

Flags: needinfo?(jmaher) → needinfo?(malexandru)

Alexandru Michis [:malexandru]

Comment 5

•

5 years ago

•

Edited

I've made retriggers for a recent push on central: https://treeherder.mozilla.org/#/jobs?repo=mozilla-central&resultStatus=success%2Ctestfailed%2Cbusted%2Cexception%2Cusercancel%2Crunnable&searchStr=linux%2Cx64%2Cquantumrender%2Copt%2Cmochitests%2Cwith%2Cfission%2Cenabled%2Ctest-linux64-qr%2Fopt-mochitest-fis-e10s-4%2Cm-fis%284%29&tochange=fd450d47a9f764098c887cfa8cdf9c390d0e3063&fromchange=8da8443e0bcb7a6d9766d179332443660c926d8b&group_state=expanded

And for the push in which the disable landed and before that: https://treeherder.mozilla.org/#/jobs?repo=mozilla-central&resultStatus=pending%2Crunning%2Csuccess%2Ctestfailed%2Cbusted%2Cexception%2Cusercancel%2Crunnable&searchStr=linux%2Cx64%2Cquantumrender%2Copt%2Cmochitests%2Cwith%2Cfission%2Cenabled%2Ctest-linux64-qr%2Fopt-mochitest-fis-e10s-4%2Cm-fis%284%29&tochange=c53f789ffabb03c04b1b20252aea9331301bfa02&group_state=expanded&fromchange=36c3240e5cafd7b57146bab3b177bfa47f42bcfa

Flags: needinfo?(malexandru)

Joel Maher ( :jmaher ) (UTC -8)

Comment 6

•

5 years ago

:ahal, this test is marked as |fails-if = fission|, but the test runs and fails ~90% of the time, resulting in a lot of oranges (see comment 5 above).

What happens is this runs and times out, sometimes it completes before the mochitest harness times it out.

I suspect the |fails-if| clause doesn't apply to harness level errors (timeouts, crashes, leaks). Can you look into this and either fix fails-if to work as expected or worse case fix the fails-if annotations to be skip-if.

Flags: needinfo?(ahal)

Comment hidden (Intermittent Failures Robot)

Andrew Halberstadt [:ahal]

Assignee

Comment 8

•

5 years ago

Just got back from PTO, I'll take a look this week.

Andrew Halberstadt [:ahal]

Assignee

Comment 9

•

5 years ago

•

Edited

The fail-if was removed in bug 1576814. So this test is just intermittent and should be skip-if'ed. Looks like that bug shouldn't have re-enabled this test.

Assignee: nobody → ahal

Status: NEW → ASSIGNED

Flags: needinfo?(ahal)

Andrew Halberstadt [:ahal]

Assignee

Comment 10

•

5 years ago

Attached file Bug 1572575 - Skip dom/tests/mochitest/whatwg/test_postMessage_userpass.html with Fission, r?#intermittent-reviewers (obsolete) — Details

Comment hidden (Intermittent Failures Robot)

Hsin-Yi Tsai (she/her) [:hsinyi]

Updated

•

5 years ago

Priority: -- → P2

Neha Kochar [:neha]

Updated

•

5 years ago

Blocks: fission-mochitests

Neha Kochar [:neha]

Updated

•

5 years ago

Fission Milestone: --- → M4

Andrew Halberstadt [:ahal]

Assignee

Updated

•

5 years ago

Status: ASSIGNED → RESOLVED

Closed: 5 years ago

Resolution: --- → DUPLICATE

Phabricator Automation

Updated

•

5 years ago

Attachment #9089396 - Attachment is obsolete: true