Closed Bug 1400516 Opened 8 years ago Closed 6 years ago

Intermittent ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testChildPass TEST-UNEXPECTED-FAIL

Categories

(Testing :: XPCShell Harness, defect, P5)

Product:
Testing
Component:
XPCShell Harness
Version:
Version 3
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: archaeopteryx [at] coole-files.de https://treeherder.mozilla.org/logviewer.html#?job_id=131410351&repo=autoland {"name":"builds-4h","url":"https://queue.taskcluster.net/v1/task/HbGNyJEJROKOXG6ANkfGnA/runs/0/artifacts/public/logs/live_backing.log"} 01:26:58 WARNING - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testChildPass TEST-UNEXPECTED-FAIL 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testDoPrintWhenVerboseExplicit PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testDoPrintWhenVerboseInManifest PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testDoPrintWhenVerboseNotExplicit PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testDoReportForeignObject PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testDoReportNonSyntaxError PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testDoReportRefError PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testDoReportSyntaxError PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testDoThrowForeignObject PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testDoThrowString PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testFail PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testHangingTimeout <- c:\mozilla-build\python\Lib\unittest\case.py SKIPPED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testKnownFail PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testLogCorrectFileName PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testMissingHeadFile PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testMozinfo PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testNoRunTestAddTask PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testNoRunTestAddTaskFail PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testNoRunTestAddTaskMultiple PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testNoRunTestAddTest PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testNoRunTestAddTestAddTask PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testNoRunTestAddTestFail PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testNoRunTestEmptyTest PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testNotSkipForAddTask PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testNotSkipForAddTest PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testPass PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testPassFail PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testRandomExecution PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testReturnNonzero PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testSkip PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testSkipForAddTask PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testSkipForAddTest PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testSyntaxError PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testUncaughtRejection PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testUncaughtRejectionJSM PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testUnexpectedPass PASSED 01:26:58 INFO - ..\testing\xpcshell\selftest.py::XPCShellTestsTests::testUnicodeInAssertMethods PASSED 01:26:58 INFO - ================================== FAILURES =================================== 01:26:58 INFO - ______________________ XPCShellTestsTests.testChildPass _______________________ 01:26:58 INFO - self = <selftest.XPCShellTestsTests testMethod=testChildPass> 01:26:58 INFO - def testChildPass(self): 01:26:58 INFO - """ 01:26:58 INFO - Check that a simple test running in a child process passes. 01:26:58 INFO - """ 01:26:58 INFO - self.writeFile("test_pass.js", SIMPLE_PASSING_TEST) 01:26:58 INFO - self.writeFile("test_child_pass.js", CHILD_TEST_PASSING) 01:26:58 INFO - self.writeManifest(["test_child_pass.js"]) 01:26:58 INFO - > self.assertTestResult(True, verbose=True) 01:26:58 INFO - ..\testing\xpcshell\selftest.py:616: 01:26:58 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 01:26:58 INFO - ..\testing\xpcshell\selftest.py:527: in assertTestResult 01:26:58 INFO - """ % ("passed" if expected else "failed", self.log.getvalue())) 01:26:58 INFO - E AssertionError: Tests should have passed, log: 01:26:58 INFO - E ======== 01:26:58 INFO - E MOZ_NODE_PATH environment variable not set. Tests requiring http/2 will fail. 01:26:58 INFO - E Running tests sequentially. 01:26:58 INFO - E SUITE-START | Running 1 tests 01:26:58 INFO - E TEST-START | test_child_pass.js 01:26:58 INFO - E test_child_pass.js | full command: ['z:\\build\\build\\src\\obj-firefox\\dist\\bin\\xpcshell.exe', '-g', 'z:\\build\\build\\src\\obj-firefox\\dist\\bin', '-a', 'z:\\build\\build\\src\\obj-firefox\\dist\\bin', '-r', 'z:/build/build/src/obj-firefox/dist/bin/components/httpd.manifest', '-m', '-s', '-e', 'const _HEAD_JS_PATH = "z:/build/build/src/testing/xpcshell/head.js";', '-e', 'const _MOZINFO_JS_PATH = "c:\\\\users\\\\task_1505519644\\\\appdata\\\\local\\\\temp\\\\xpc-profile-sbx3mf\\\\mozinfo.json";', '-e', 'const _TESTING_MODULES_DIR = "z:\\\\build\\\\build\\\\src\\\\obj-firefox\\\\_tests\\\\modules\\\\";', '-f', 'z:\\build\\build\\src\\testing\\xpcshell\\head.js', '-e', 'const _SERVER_ADDR = "localhost"', '-e', 'const _HEAD_FILES = [];', '-e', 'const _JSDEBUGGER_PORT = 0;', '-e', 'const _TEST_FILE = ["c:/users/task_1505519644/appdata/local/temp/tmp_derjw/test_child_pass.js"];', '-e', 'const _TEST_NAME = "test_child_pass.js"', '-e', '_execute_test(); quit(0);'] 01:26:58 INFO - E test_child_pass.js | current directory: 'c:\\users\\task_1505519644\\appdata\\local\\temp\\tmp_derjw' 01:26:58 INFO - E test_child_pass.js | environment: ['XPCSHELL_TEST_PROFILE_DIR=c:\\users\\task_1505519644\\appdata\\local\\temp\\xpc-profile-sbx3mf', 'MOZ_CRASHREPORTER=1', 'MOZ_DISABLE_CONTENT_SANDBOX=1', 'MOZ_DEVELOPER_REPO_DIR=z:/build/build/src', 'MOZ_DISABLE_NONLOCAL_CONNECTIONS=1', 'PATH=z:\\build\\build\\src\\obj-firefox\\_virtualenv\\Scripts;z:\\build\\build\\src\\clang\\bin;z:\\build\\build\\src\\vs2015u3\\VC\\bin\\amd64;z:\\build\\build\\src\\vs2015u3\\VC\\bin;z:\\build\\build\\src\\vs2015u3\\SDK\\bin\\x64;z:\\build\\build\\src\\vs2015u3\\VC\\redist\\x64\\Microsoft.VC140.CRT;z:\\build\\build\\src\\vs2015u3\\SDK\\Redist\\ucrt\\DLLs\\x64;z:\\build\\build\\src\\vs2015u3\\DIA SDK\\bin\\amd64;c:\\Program Files\\Mercurial;c:\\mozilla-build\\7zip;c:\\mozilla-build\\info-zip;c:\\mozilla-build\\kdiff3;c:\\mozilla-build\\moztools-x64\\bin;c:\\mozilla-build\\mozmake;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\nsis-3.01;c:\\mozilla-build\\nsis-3.0b3;c:\\mozilla-build\\nsis-2.46u;c:\\mozilla-build\\python;c:\\mozilla-build\\python\\Scripts;c:\\mozilla-build\\upx391w;c:\\mozilla-build\\wget;c:\\mozilla-build\\yasm;c:\\Windows\\system32;c:\\Windows;c:\\Windows\\System32\\Wbem;c:\\Windows\\System32\\WindowsPowerShell\\v1.0\\;c:\\Program Files\\Amazon\\cfn-bootstrap\\;c:\\Program Files (x86)\\GNU\\GnuPG\\pub;c:\\Program Files (x86)\\Windows Kits\\10\\Windows Performance Toolkit\\;c:\\mozilla-build\\python\\lib\\site-packages\\pywin32_system32;c:\\mozilla-build\\python\\lib\\site-packages\\pywin32_system32;c:\\mozilla-build\\python\\lib\\site-packages\\pywin32_system32;c:\\mozilla-build\\python\\lib\\site-packages\\pywin32_system32;z:\\build\\build\\src\\obj-firefox\\dist\\bin', 'XPCSHELL_TEST_TEMP_DIR=c:\\users\\task_1505519644\\appdata\\local\\temp\\xpc-other-bmbtsk'] 01:26:58 INFO - E PID 4660 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties 01:26:58 INFO - E PID 4660 | [4660, Main Thread] WARNING: Failed to get directory to cache.: file z:/build/build/src/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp, line 90 01:26:58 INFO - E PID 4660 | [4660, Main Thread] WARNING: Failed to get directory to cache.: file z:/build/build/src/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp, line 90 01:26:58 INFO - E PID 4660 | [4660, Main Thread] WARNING: Couldn't get the user appdata directory. Crash events may not be produced.: file z:/build/build/src/toolkit/crashreporter/nsExceptionHandler.cpp, line 2872 01:26:58 INFO - E (xpcshell/head.js) | test MAIN run_test pending (1) 01:26:58 INFO - E (xpcshell/head.js) | test run_next_test 0 pending (2) 01:26:58 INFO - E (xpcshell/head.js) | test MAIN run_test finished (2) 01:26:58 INFO - E running event loop 01:26:58 INFO - E PID 4660 | [4660, 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 370 01:26:58 INFO - E test_child_pass.js | Starting test_child_simple 01:26:58 INFO - E (xpcshell/head.js) | test test_child_simple pending (2) 01:26:58 INFO - E PID 4660 | [Parent 4660, Main Thread] WARNING: Couldn't get the user appdata directory, crash dumps will go in an unusual location: file z:/build/build/src/toolkit/crashreporter/nsExceptionHandler.cpp, line 2949 01:26:58 INFO - E PID 4660 | [Parent 4660, Main Thread] WARNING: Couldn't get the user appdata directory. Crash events may not be produced.: file z:/build/build/src/toolkit/crashreporter/nsExceptionHandler.cpp, line 2872 01:26:58 INFO - E PID 4660 | [Parent 4660, Main Thread] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/extensions/spellcheck/src/mozPersonalDictionary.cpp, line 219 01:26:58 INFO - E PID 4660 | Unable to read VR Path Registry from Z:\\task_1505519644\\AppData\\Local\\openvr\\openvrpaths.vrpath 01:26:58 INFO - E PID 4660 | [Parent 4660, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, NS_ERROR_UNEXPECTED) failed with result 0x80004005: file z:/build/build/src/extensions/cookie/nsPermissionManager.cpp, line 981 01:26:58 INFO - E PID 4660 | [Parent 4660, Main Thread] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/dom/media/gmp/GMPServiceParent.cpp, line 212 01:26:58 INFO - E (xpcshell/head.js) | test run in child pending (3) 01:26:58 INFO - E (xpcshell/head.js) | test run_next_test 1 pending (4) 01:26:58 INFO - E (xpcshell/head.js) | test test_child_simple finished (4) 01:26:58 INFO - E (xpcshell/head.js) | test run_next_test 0 finished (3) 01:26:58 INFO - E (xpcshell/head.js) | test run_next_test 1 finished (2) 01:26:58 INFO - E PID 4660 | [Parent 4660, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004002: file z:/build/build/src/toolkit/components/resistfingerprinting/nsRFPService.cpp, line 182 01:26:58 INFO - E CHILD-TEST-STARTED 01:26:58 INFO - E (xpcshell/head.js) | test MAIN run_test pending (1) 01:26:58 INFO - E TEST-PASS | test_child_pass.js | run_test - [run_test : 1] true == true 01:26:58 INFO - E (xpcshell/head.js) | test MAIN run_test finished (1) 01:26:58 INFO - E exiting test 01:26:58 INFO - E PID 4660 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties 01:26:58 INFO - E PID 4660 | Unable to read VR Path Registry from Z:\\task_1505519644\\AppData\\Local\\openvr\\openvrpaths.vrpath 01:26:58 INFO - E PID 4660 | [Child 1396, 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 370 01:26:58 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: 356}]" 01:26:58 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: 356}]" 01:26:58 INFO - E CHILD-TEST-COMPLETED 01:26:58 INFO - E (xpcshell/head.js) | test finished (1) 01:26:58 INFO - E exiting test 01:26:58 INFO - E PID 4660 | [Parent 4660, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 01:26:58 INFO - E PID 4660 | [Child 1396, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 01:26:58 INFO - E PID 4660 | [Child 1396, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 01:26:58 INFO - E PID 4660 | [Parent 4660, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 01:26:58 INFO - E PID 4660 | ###!!! [Child][MessageChannel] Error: (msgtype=0x200009,name=PCompositorBridge::Msg___delete__) Channel error: cannot send/recv 01:26:58 INFO - E PID 4660 | [Child 1396, Main Thread] ###!!! ASSERTION: reacquiring already acquired resource: '!IsAcquired()', file z:/build/build/src/xpcom/threads/BlockingResourceBase.cpp, line 323 01:26:58 INFO - E PID 4660 | #01: mozilla::ipc::MessageChannel::Close (z:\\build\\build\\src\\ipc\\glue\\MessageChannel.cpp:2695) 01:26:58 INFO - E PID 4660 | #02: mozilla::layers::CompositorManagerChild::Shutdown (z:\\build\\build\\src\\gfx\\layers\\ipc\\CompositorManagerChild.cpp:71) 01:26:58 INFO - E PID 4660 | #03: gfxPlatform::ShutdownLayersIPC (z:\\build\\build\\src\\gfx\\thebes\\gfxPlatform.cpp:1054) 01:26:58 INFO - E PID 4660 | #04: mozilla::ShutdownXPCOM (z:\\build\\build\\src\\xpcom\\build\\XPCOMInit.cpp:886) 01:26:58 INFO - E PID 4660 | #05: XRE_TermEmbedding (z:\\build\\build\\src\\toolkit\\xre\\nsEmbedFunctions.cpp:225) 01:26:58 INFO - E PID 4660 | #06: mozilla::ipc::ScopedXREEmbed::Stop (z:\\build\\build\\src\\ipc\\glue\\ScopedXREEmbed.cpp:118) 01:26:58 INFO - E PID 4660 | #07: XRE_InitChildProcess (z:\\build\\build\\src\\toolkit\\xre\\nsEmbedFunctions.cpp:710) 01:26:58 INFO - E PID 4660 | #08: content_process_main (z:\\build\\build\\src\\ipc\\contentproc\\plugin-container.cpp:63) 01:26:58 INFO - E PID 4660 | #09: NS_internal_main (z:\\build\\build\\src\\ipc\\app\\MozillaRuntimeMain.cpp:25) 01:26:58 INFO - E PID 4660 | #10: wmain (z:\\build\\build\\src\\toolkit\\xre\\nsWindowsWMain.cpp:111) 01:26:58 INFO - E PID 4660 | #11: __scrt_common_main_seh (f:\\dd\\vctools\\crt\\vcstartup\\src\\startup\\exe_common.inl:253) 01:26:58 INFO - E PID 4660 | #12: KERNEL32.DLL + 0x13d2 01:26:58 INFO - E PID 4660 | #13: ntdll.dll + 0x154e4 01:26:58 INFO - E PID 4660 | [Child 1396, Main Thread] ###!!! ASSERTION: reacquiring already acquired resource: '!IsAcquired()', file z:/build/build/src/xpcom/threads/BlockingResourceBase.cpp, line 323 01:26:58 INFO - E PID 4660 | Hit MOZ_CRASH() at z:/build/build/src/memory/mozalloc/mozalloc_abort.cpp:33 01:26:58 INFO - E PID 4660 | [Parent 4660, Main Thread] WARNING: NS_ENSURE_TRUE(maybeContext) failed: file z:/build/build/src/xpcom/threads/nsThread.cpp, line 797 01:26:58 INFO - E PID 4660 | nsStringStats 01:26:58 INFO - E PID 4660 | => mAllocCount: 13468 01:26:58 INFO - E PID 4660 | => mReallocCount: 535 01:26:58 INFO - E PID 4660 | => mFreeCount: 13468 01:26:58 INFO - E PID 4660 | => mShareCount: 8484 01:26:58 INFO - E PID 4660 | => mAdoptCount: 241 01:26:58 INFO - E PID 4660 | => mAdoptFreeCount: 241 01:26:58 INFO - E PID 4660 | => Process ID: 4660, Thread ID: 3092 01:26:58 INFO - E TEST-PASS | test_child_pass.js | took 1126ms 01:26:58 INFO - E mozcrash Saved minidump as z:\build\public\build\c395c01a-e4fb-4dd7-8293-6241d4ac55b3.dmp 01:26:58 INFO - E mozcrash Saved app info as z:\build\public\build\c395c01a-e4fb-4dd7-8293-6241d4ac55b3.extra 01:26:58 INFO - E INFO | Result summary: 01:26:58 INFO - E INFO | Passed: 1 01:26:58 INFO - E INFO | Failed: 1 01:26:58 INFO - E INFO | Todo: 0 01:26:58 INFO - E INFO | Retried: 0 01:26:58 INFO - E SUITE-END | took 1s 01:26:58 INFO - E 01:26:58 INFO - E ========
https://wiki.mozilla.org/Bugmasters#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 8 years ago
Resolution: --- → INCOMPLETE
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: REOPENED → RESOLVED
Closed: 8 years ago → 8 years ago
Resolution: --- → INCOMPLETE
Recent log failure: https://treeherder.mozilla.org/logviewer.html#?job_id=201685960&repo=autoland&lineNumber=98686
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
The recent change appears to be from Bug 1491186, Dustin can you please provide some input here? There are 22 total failures in the last 7 days.
Flags: needinfo?(dustin)
See Also: → 1494633
Sorry, how is this related? The bug you linked is NPTOB so I'm not sure how it could be related to test failures.
Flags: needinfo?(dustin)
(In reply to Dustin J. Mitchell [:dustin] pronoun: he from comment #9) > Sorry, how is this related? The bug you linked is NPTOB so I'm not sure how > it could be related to test failures. I looked at the most recent occurrence and went from there. I don't know how it can be related, that is why I asked you, being that on the push where that bug landed, this occurred again.
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: REOPENED → RESOLVED
Closed: 8 years ago → 7 years ago
Resolution: --- → INCOMPLETE

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Status: REOPENED → RESOLVED
Closed: 7 years ago → 7 years ago
Resolution: --- → INCOMPLETE

Recent log failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=259088042&repo=autoland&lineNumber=43743

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Status: REOPENED → RESOLVED
Closed: 7 years ago → 6 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.