Recent log:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=227878616&repo=autoland&lineNumber=42071
INFO - z:\build\build\src\testing\xpcshell\selftest.py
11:19:29 INFO - ============================= test session starts =============================
11:19:29 INFO - platform win32 -- Python 2.7.15, pytest-3.6.2, py-1.5.4, pluggy-0.6.0 -- z:\build\build\src\obj-firefox_virtualenvs\obj-firefox-sNC2NmI7-2.7\Scripts\python.exe
11:19:29 INFO - rootdir: z:\build\build\src, inifile: z:\build\build\src\config\mozunit\mozunit\pytest.ini
11:19:29 INFO - collecting ... collected 55 items
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testAddTaskRunNextTest PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testAddTaskSkip PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testAddTaskSkipAll PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testAddTaskStackTrace PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testAddTaskTestFailureInside PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testAddTaskTestMultiple PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testAddTaskTestRejected PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testAddTaskTestSingle PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testAddTestFailing PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testAddTestSimple PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testAddTestUncaughtRejection PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testAddTestUncaughtRejectionJSM PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testAssertStack <- c:\mozilla-build\python\Lib\unittest\case.py SKIPPED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testAsyncCleanup PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testChild PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testChildFail PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testChildHang PASSED
11:19:29 WARNING - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testChildMozinfo TEST-UNEXPECTED-FAIL
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testChildPass PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testDoPrintWhenVerboseExplicit PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testDoPrintWhenVerboseInManifest PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testDoPrintWhenVerboseNotExplicit PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testDoReportForeignObject PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testDoReportNonSyntaxError PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testDoReportRefError PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testDoReportSyntaxError PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testDoThrowForeignObject PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testDoThrowString PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testFail PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testHangingTimeout <- c:\mozilla-build\python\Lib\unittest\case.py SKIPPED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testKnownFail PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testLogCorrectFileName PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testMissingHeadFile PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testMozinfo PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testNoRunTestAddTask PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testNoRunTestAddTaskFail PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testNoRunTestAddTaskMultiple PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testNoRunTestAddTest PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testNoRunTestAddTestAddTask PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testNoRunTestAddTestFail PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testNoRunTestEmptyTest PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testNotSkipForAddTask PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testNotSkipForAddTest PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testPass PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testPassFail PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testRandomExecution PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testReturnNonzero PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testSkip PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testSkipForAddTask PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testSkipForAddTest PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testSyntaxError PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testUncaughtRejection PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testUncaughtRejectionJSM PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testUnexpectedPass PASSED
11:19:29 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testUnicodeInAssertMethods PASSED
11:19:29 INFO - ================================== FAILURES ===================================
11:19:29 INFO - _____________________ XPCShellTestsTests.testChildMozinfo _____________________
11:19:29 INFO - self = <selftest.XPCShellTestsTests testMethod=testChildMozinfo>
11:19:29 INFO - def testChildMozinfo(self):
11:19:29 INFO - """
11:19:29 INFO - Check that mozinfo.json is loaded in child process
11:19:29 INFO - """
11:19:29 INFO - self.writeFile("test_mozinfo.js", LOAD_MOZINFO)
11:19:29 INFO - self.writeFile("test_child_mozinfo.js", CHILD_MOZINFO)
11:19:29 INFO - self.writeManifest(["test_child_mozinfo.js"])
11:19:29 INFO - > self.assertTestResult(True)
11:19:29 INFO - ..\testing\xpcshell\selftest.py:1366:
11:19:29 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
11:19:29 INFO - ..\testing\xpcshell\selftest.py:527: in assertTestResult
11:19:29 INFO - """ % ("passed" if expected else "failed", self.log.getvalue()))
11:19:29 INFO - E AssertionError: Tests should have passed, log:
11:19:29 INFO - E ========
11:19:29 INFO - E runxpcshelltests.py | using symbolizer at z:\build\build\src\obj-firefox\dist\bin\llvm-symbolizer.exe
11:19:29 INFO - E MOZ_NODE_PATH environment variable not set. Tests requiring http/2 will fail.
11:19:29 INFO - E Running tests sequentially.
11:19:29 INFO - E SUITE-START | Running 1 tests
11:19:29 INFO - E TEST-START | test_child_mozinfo.js
11:19:29 WARNING - E TEST-UNEXPECTED-FAIL | test_child_mozinfo.js | xpcshell return code: -2147483645
11:19:29 INFO - E TEST-INFO took 3375ms
11:19:29 INFO - E >>>>>>>
11:19:29 INFO - E PID 1276 | Unable to load \untrusted-startup-test-dll.dll; LoadLibraryW failed: 126Couldn't convert chrome URL: chrome://branding/locale/brand.properties
11:19:29 INFO - E PID 1276 | [1276, Main Thread] WARNING: Failed to get directory to cache.: file z:/build/build/src/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp, line 81
11:19:29 INFO - E PID 1276 | [1276, Main Thread] WARNING: Failed to get directory to cache.: file z:/build/build/src/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp, line 81
11:19:29 INFO - E PID 1276 | [1276, Main Thread] WARNING: Failed to get directory to cache.: file z:/build/build/src/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp, line 81
11:19:29 INFO - E PID 1276 | [1276, Main Thread] WARNING: Failed to get directory to cache.: file z:/build/build/src/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp, line 81
11:19:29 INFO - E PID 1276 | [1276, Main Thread] WARNING: Failed to get directory to cache.: file z:/build/build/src/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp, line 81
11:19:29 INFO - E (xpcshell/head.js) | test MAIN run_test pending (1)
11:19:29 INFO - E (xpcshell/head.js) | test run_next_test 0 pending (2)
11:19:29 INFO - E (xpcshell/head.js) | test MAIN run_test finished (2)
11:19:29 INFO - E running event loop
11:19:29 INFO - E PID 1276 | [1276, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file z:/build/build/src/dom/media/CubebUtils.cpp, line 358
11:19:29 INFO - E test_child_mozinfo.js | Starting test_child_mozinfo
11:19:29 INFO - E (xpcshell/head.js) | test test_child_mozinfo pending (2)
11:19:29 INFO - E PID 1276 | [Parent 1276, Main Thread] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/extensions/spellcheck/src/mozPersonalDictionary.cpp, line 208
11:19:29 INFO - E PID 1276 | [Parent 1276, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, NS_ERROR_UNEXPECTED) failed with result 0x80004005: file z:/build/build/src/extensions/cookie/nsPermissionManager.cpp, line 1060
11:19:29 INFO - E PID 1276 | [Parent 1276, Main Thread] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/dom/media/gmp/GMPServiceParent.cpp, line 208
11:19:29 INFO - E PID 1276 | [Parent 1276, Main Thread] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/dom/media/gmp/GMPServiceParent.cpp, line 125
11:19:29 INFO - E (xpcshell/head.js) | test run in child pending (3)
11:19:29 INFO - E (xpcshell/head.js) | test run_next_test 1 pending (4)
11:19:29 INFO - E (xpcshell/head.js) | test test_child_mozinfo finished (4)
11:19:29 INFO - E (xpcshell/head.js) | test run_next_test 0 finished (3)
11:19:29 INFO - E (xpcshell/head.js) | test run_next_test 1 finished (2)
11:19:29 INFO - E PID 1276 | [Child 3264, Main Thread] WARNING: No CID found when attempting to map contract ID: file z:/build/build/src/xpcom/components/nsComponentManager.cpp, line 744
11:19:29 INFO - E PID 1276 | [Parent 1276, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, kKnownEsrVersion) failed with result 0x80004002: file z:/build/build/src/toolkit/components/resistfingerprinting/nsRFPService.cpp, line 662
11:19:29 INFO - E CHILD-TEST-STARTED
11:19:29 INFO - E (xpcshell/head.js) | test MAIN run_test pending (1)
11:19:29 INFO - E TEST-PASS | test_child_mozinfo.js | run_test - [run_test : 3] "object" != "undefined"
11:19:29 INFO - E TEST-PASS | test_child_mozinfo.js | run_test - [run_test : 4] "string" != "undefined"
11:19:29 INFO - E (xpcshell/head.js) | test MAIN run_test finished (1)
11:19:29 INFO - E exiting test
11:19:29 INFO - E PID 1276 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
11:19:29 INFO - E PID 1276 | [Child 3264, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file z:/build/build/src/dom/media/CubebUtils.cpp, line 358
11:19:29 INFO - E "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "z:/build/build/src/testing/xpcshell/head.js" line: 349}]"
11:19:29 INFO - E "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "z:/build/build/src/testing/xpcshell/head.js" line: 349}]"
11:19:29 INFO - E CHILD-TEST-COMPLETED
11:19:29 INFO - E (xpcshell/head.js) | test finished (1)
11:19:29 INFO - E exiting test
11:19:29 INFO - E PID 1276 | [Parent 1276, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
11:19:29 INFO - E PID 1276 | [Child 3264, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
11:19:29 INFO - E PID 1276 | [Child 3264, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
11:19:29 INFO - E PID 1276 | [Parent 1276, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
11:19:29 INFO - E PID 1276 | [Parent 1276, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
11:19:29 INFO - E PID 1276 | [Parent 1276, Gecko_IOThread] WARNING: pipe err
11:19:29 INFO - E PID 1276 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
11:19:29 INFO - E PID 1276 | or: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
11:19:29 INFO - E PID 1276 | [Parent 1276, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
11:19:29 INFO - E PID 1276 | nsStringStats
11:19:29 INFO - E PID 1276 | => mAllocCount: 3982
11:19:29 INFO - E PID 1276 | => mReallocCount: 0
11:19:29 INFO - E PID 1276 | => mFreeCount: 3982
11:19:29 INFO - E PID 1276 | => mShareCount: 2446
11:19:29 INFO - E PID 1276 | => mAdoptCount: 121
11:19:29 INFO - E PID 1276 | => mAdoptFreeCount: 121
11:19:29 INFO - E PID 1276 | => Process ID: 3264, Thread ID: 5012
11:19:29 INFO - E PID 1276 | Assertion failure: work_queue_.empty(), at z:/build/build/src/ipc/chromium/src/base/message_loop.cc:490
11:19:29 INFO - E PID 1276 | #01: base::Thread::ThreadMain (z:\build\build\src\ipc\chromium\src\base\thread.cc:209)
11:19:29 INFO - E PID 1276 | #02: `anonymous namespace'::ThreadFunc (z:\build\build\src\ipc\chromium\src\base\platform_thread_win.cc:31)
11:19:29 INFO - E PID 1276 | #03: _asan_default_suppressions__dll[z:\build\build\src\obj-firefox\dist\bin\clang_rt.asan_dynamic-x86_64.dll +0x3e889]
11:19:29 INFO - E PID 1276 | #04: BaseThreadInitThunk[C:\Windows\system32\KERNEL32.DLL +0x13d2]
11:19:29 INFO - E PID 1276 | #05: patched_BaseThreadInitThunk (z:\build\build\src\mozglue\build\WindowsDllBlocklist.cpp:734)
11:19:29 INFO - E PID 1276 | #06: RtlUserThreadStart[C:\Windows\SYSTEM32\ntdll.dll +0x154e4]
11:19:29 INFO - E <<<<<<<
11:19:29 INFO - E INFO | Result summary:
11:19:29 INFO - E INFO | Passed: 0
11:19:29 INFO - E INFO | Failed: 1
11:19:29 INFO - E INFO | Todo: 0
11:19:29 INFO - E INFO | Retried: 0
11:19:29 INFO - E SUITE-END | took 3s
11:19:29 INFO - E
11:19:29 INFO - E ========
11:19:29 INFO - =============== 1 failed, 52 passed, 2 skipped in 59.21 seconds ===============
11:19:29 INFO - Setting retcode to 1 from z:\build\build\src\testing\xpcshell\selftest.py
11:20:58 INFO - z:\build\build\src\python\mozbuild\mozbuild\test\configure\test_toolchain_configure.py
Description
•