Filed by: smolnar [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=404471943&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/WzazBMrTTq69x8bYvHtwvQ/runs/0/artifacts/public/logs/live_backing.log
INFO - TEST-START | dom/base/test/test_bug466080.html
[task 2023-02-03T09:56:41.206Z] 09:56:41 INFO - GECKO(3532) | [Child 1788, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3384
[task 2023-02-03T09:56:41.217Z] 09:56:41 INFO - GECKO(3532) | [Child 1788, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-02-03T09:56:41.221Z] 09:56:41 INFO - GECKO(3532) | [Parent 4680, Main Thread] WARNING: WebProgress Ignored: no longer current window global: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:3025
[task 2023-02-03T09:56:41.226Z] 09:56:41 INFO - GECKO(3532) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_167541407660216\AppData\Local\Temp\tmpokjaupas.mozrunner\runtests_leaks_tab_pid5232.log
[task 2023-02-03T09:56:41.226Z] 09:56:41 INFO - GECKO(3532) | [5232, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-02-03T09:56:41.230Z] 09:56:41 INFO - GECKO(3532) | [Child 1788, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4663
[task 2023-02-03T09:56:41.241Z] 09:56:41 INFO - GECKO(3532) | [Child 5232, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:478
[task 2023-02-03T09:56:41.268Z] 09:56:41 INFO - GECKO(3532) | [Child 5232, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:478
[task 2023-02-03T09:56:41.268Z] 09:56:41 INFO - GECKO(3532) | [Child 5232, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:478
[task 2023-02-03T09:56:41.288Z] 09:56:41 INFO - GECKO(3532) | [Child 5232, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:478
[task 2023-02-03T09:56:41.375Z] 09:56:41 INFO - GECKO(3532) | [Parent 4680, Main Thread] WARNING: NS_ENSURE_TRUE(LoadIsPending()) failed: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpChannel.cpp:5707
[task 2023-02-03T09:56:41.404Z] 09:56:41 INFO - GECKO(3532) | [Parent 4680, Main Thread] WARNING: NS_ENSURE_TRUE(mSuspendCount > 0) failed: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpChannel.cpp:5732
[task 2023-02-03T09:56:41.532Z] 09:56:41 INFO - GECKO(3532) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_167541407660216\AppData\Local\Temp\tmpokjaupas.mozrunner\runtests_leaks_tab_pid756.log
[task 2023-02-03T09:56:41.534Z] 09:56:41 INFO - GECKO(3532) | [756, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-02-03T09:56:41.545Z] 09:56:41 INFO - GECKO(3532) | [Child 756, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:478
[task 2023-02-03T09:56:41.575Z] 09:56:41 INFO - GECKO(3532) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_167541407660216\AppData\Local\Temp\tmpokjaupas.mozrunner\runtests_leaks_tab_pid2696.log
[task 2023-02-03T09:56:41.575Z] 09:56:41 INFO - GECKO(3532) | [2696, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-02-03T09:56:41.579Z] 09:56:41 INFO - GECKO(3532) | [Child 756, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:478
[task 2023-02-03T09:56:41.581Z] 09:56:41 INFO - GECKO(3532) | [Child 756, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:478
[task 2023-02-03T09:56:41.593Z] 09:56:41 INFO - GECKO(3532) | [Child 2696, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:478
[task 2023-02-03T09:56:41.598Z] 09:56:41 INFO - GECKO(3532) | [Child 756, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:478
[task 2023-02-03T09:56:41.626Z] 09:56:41 INFO - GECKO(3532) | [Child 2696, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:478
[task 2023-02-03T09:56:41.631Z] 09:56:41 INFO - GECKO(3532) | [Child 2696, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:478
[task 2023-02-03T09:56:41.642Z] 09:56:41 INFO - GECKO(3532) | [Child 2696, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:478
[task 2023-02-03T09:57:44.846Z] 09:57:44 INFO - GECKO(3532) | [Parent 4680, Jump List] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012 (NS_ERROR_FILE_NOT_FOUND): file /builds/worker/checkouts/gecko/widget/windows/WinUtils.cpp:1076
[task 2023-02-03T09:59:40.781Z] 09:59:40 INFO - [Parent 4344, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/components/places/Database.cpp:519
[task 2023-02-03T09:59:40.781Z] 09:59:40 INFO - [Parent 4344, Main Thread] WARNING: Unable to get a connection to vacuum database: file /builds/worker/checkouts/gecko/storage/VacuumManager.cpp:130
[task 2023-02-03T09:59:40.811Z] 09:59:40 INFO - console.error: (new TypeError("connection not specified or invalid.", "resource://gre/modules/Sqlite.sys.mjs", 1363))
[task 2023-02-03T09:59:40.812Z] 09:59:40 INFO - [Parent 4344, IPDL Background] WARNING: QM_TRY failure (ERROR): 'OkIf(gBasePath)', file dom/quota/ActorsParent.cpp:2816
[task 2023-02-03T09:59:40.813Z] 09:59:40 INFO - [Parent 4344, IPDL Background] WARNING: Trying to create QuotaManager before profile-do-change! Forgot to call do_get_profile()?: file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2815
[task 2023-02-03T09:59:40.813Z] 09:59:40 INFO - [Parent 4344, IPDL Background] WARNING: QM_TRY failure (ERROR): 'GetOrCreate().map([](const auto& res) { return Ok{}; }) failed with resultCode 0x80004005, resultName NS_ERROR_FAILURE', file dom/quota/ActorsParent.cpp:2835
[task 2023-02-03T09:59:40.813Z] 09:59:40 INFO - [Parent 4344, IPDL Background] WARNING: QM_TRY failure (ERROR): 'QuotaManager::EnsureCreated() failed with resultCode 0x80004005, resultName NS_ERROR_FAILURE', file dom/quota/ActorsParent.cpp:7794
[task 2023-02-03T09:59:44.866Z] 09:59:44 INFO - GECKO(3532) | [Parent 4680, Jump List] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012 (NS_ERROR_FILE_NOT_FOUND): file /builds/worker/checkouts/gecko/widget/windows/WinUtils.cpp:1076
[task 2023-02-03T10:00:12.074Z] 10:00:12 INFO - GECKO(3532) | [WARN rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
INFO - GECKO(3532) | console.error: (new Error("Polling for changes failed: Unexpected content-type \"text/plain;charset=US-ASCII\".", "resource://services-settings/remote-settings.js", 334))
[task 2023-02-03T10:01:40.807Z] 10:01:40 INFO - console.error: (new TypeError("can't access property \"executeTransaction\", db is undefined", "resource://gre/modules/PlacesFrecencyRecalculator.sys.mjs", 174))
[task 2023-02-03T10:01:48.840Z] 10:01:48 INFO - TEST-INFO | started process screenshot
[task 2023-02-03T10:01:48.931Z] 10:01:48 INFO - TEST-INFO | screenshot: exit 0
[task 2023-02-03T10:01:48.935Z] 10:01:48 INFO - TEST-UNEXPECTED-FAIL | dom/base/test/test_bug466080.html | Test timed out. -
[task 2023-02-03T10:01:49.851Z] 10:01:49 INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-02-03T10:01:49.854Z] 10:01:49 INFO - TEST-UNEXPECTED-FAIL | dom/base/test/test_bug466080.html | [SimpleTest.finish()] No checks actually run. (You need to call ok(), is(), or similar functions at least once. Make sure you use SimpleTest.waitForExplicitFinish() if you need it.)
[task 2023-02-03T10:01:49.854Z] 10:01:49 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:404:16
[task 2023-02-03T10:01:49.854Z] 10:01:49 INFO - afterCleanup@SimpleTest/SimpleTest.js:1425:18
[task 2023-02-03T10:01:49.854Z] 10:01:49 INFO - executeCleanupFunction@SimpleTest/SimpleTest.js:1501:7
[task 2023-02-03T10:01:49.854Z] 10:01:49 INFO - SimpleTest.finish@SimpleTest/SimpleTest.js:1521:3
[task 2023-02-03T10:01:49.854Z] 10:01:49 INFO - @SimpleTest/SimpleTest.js:2187:16
[task 2023-02-03T10:01:49.864Z] 10:01:49 INFO - GECKO(3532) | MEMORY STAT | vsize 632MB | vsizeMaxContiguous 1806MB | residentFast 65MB | heapAllocated 5MB
[task 2023-02-03T10:01:49.875Z] 10:01:49 INFO - TEST-OK | dom/base/test/test_bug466080.html | took 308697ms
Description
•