JDK-8143876 : test/java/lang/ProcessHandle/TreeTest.java failed intermittently with assertion error
  • Type: Bug
  • Component: core-libs
  • Sub-Component: java.lang
  • Affected Version: 9
  • Priority: P4
  • Status: Resolved
  • Resolution: Fixed
  • OS: windows
  • CPU: generic
  • Submitted: 2015-11-24
  • Updated: 2015-12-10
  • Resolved: 2015-12-02
The Version table provides details related to the release that this issue/RFE will be addressed.

Unresolved : Release in which this issue/RFE will be addressed.
Resolved: Release in which this issue/RFE has been resolved.
Fixed : Release in which this issue/RFE has been fixed. The release containing this fix may be available for download as an Early Access Release or a General Availability Release.

To download the current JDK release, click here.
JDK 9
9 b96Fixed
Related Reports
Duplicate :  
Description
This happened in mach 5 testing for latest jdk 9 dev. There are other intermittent issues, such as JDK-8142530. But the error is different in detail.

Please see the following test output:

----------System.out:(133/16648)*----------
[TestNG] Running:
  java/lang/ProcessHandle/TreeTest.java

self pid: 76752
pid 76752, alive: true; parent: Optional[79908], [user: Optional[SCAAA070\\mach5-one], cmd: C:\\jenkins\\workspace\\9-dev-tier2-windows-i586\\build\\jdk\\bin\\java.exe, startTime: Optional[2015-11-23T20:37:42.955Z], totalTime: Optional[PT0.546875S]]
test TreeTest.test1(): success
  p1 pid: 79468
 waiting for subprocesses of 79468 to start, expected: 3, current: 0
 waiting for subprocesses of 79468 to start, expected: 3, current: 7
test TreeTest.test2(): failure
java.lang.AssertionError: Child should be alive: 824 expected [true] but found [false]
	at org.testng.Assert.fail(Assert.java:94)
	at org.testng.Assert.failNotEquals(Assert.java:494)
	at org.testng.Assert.assertTrue(Assert.java:42)
	at TreeTest.test2(TreeTest.java:172)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:520)
	at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:84)
	at org.testng.internal.Invoker.invokeMethod(Invoker.java:714)
	at org.testng.internal.Invoker.invokeTestMethod(Invoker.java:901)
	at org.testng.internal.Invoker.invokeTestMethods(Invoker.java:1231)
	at org.testng.internal.TestMethodWorker.invokeTestMethods(TestMethodWorker.java:127)
	at org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:111)
	at org.testng.TestRunner.privateRun(TestRunner.java:767)
	at org.testng.TestRunner.run(TestRunner.java:617)
	at org.testng.SuiteRunner.runTest(SuiteRunner.java:334)
	at org.testng.SuiteRunner.runSequentially(SuiteRunner.java:329)
	at org.testng.SuiteRunner.privateRun(SuiteRunner.java:291)
	at org.testng.SuiteRunner.run(SuiteRunner.java:240)
	at org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:52)
	at org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:86)
	at org.testng.TestNG.runSuitesSequentially(TestNG.java:1224)
	at org.testng.TestNG.runSuitesLocally(TestNG.java:1149)
	at org.testng.TestNG.run(TestNG.java:1057)
	at com.sun.javatest.regtest.TestNGAction$TestNGRunner.main(TestNGAction.java:163)
	at com.sun.javatest.regtest.TestNGAction$TestNGRunner.main(TestNGAction.java:147)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:520)
	at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:92)
	at java.lang.Thread.run(Thread.java:747)
 p1: 33880
test TreeTest.test3(): success
    Parent     Child  Info
        null,       0, []
        null,       4, []
        null,     228, [, cmd: C:\\Windows\\System32\\svchost.exe, startTime: Optional[2015-10-31T13:16:12.912Z], totalTime: Optional[PT1M7.84375S]]
        null,     576, []
        null,     616, [user: Optional[NT AUTHORITY\\SYSTEM], cmd: C:\\Windows\\System32\\svchost.exe, startTime: Optional[2015-10-31T13:16:13.489Z], totalTime: Optional[PT1H5M5.921875S]]
        null,     684, [, cmd: C:\\Windows\\System32\\svchost.exe, startTime: Optional[2015-10-31T13:16:13.361Z], totalTime: Optional[PT20M17.859375S]]
        null,     772, []
        null,     832, [user: Optional[NT AUTHORITY\\SYSTEM], cmd: C:\\Windows\\System32\\wininit.exe, startTime: Optional[2015-10-31T13:16:04.387Z], totalTime: Optional[PT0.484375S]]
        null,     932, [, cmd: C:\\Windows\\System32\\svchost.exe, startTime: Optional[2015-10-31T13:16:13.664Z], totalTime: Optional[PT11.859375S]]
        null,    1000, [, cmd: C:\\Windows\\System32\\svchost.exe, startTime: Optional[2015-10-31T13:16:12.721Z], totalTime: Optional[PT59.21875S]]
        null,    1100, [, cmd: C:\\Windows\\System32\\svchost.exe, startTime: Optional[2015-10-31T13:16:13.943Z], totalTime: Optional[PT59.1875S]]
        null,    1316, [, cmd: C:\\Windows\\System32\\svchost.exe, startTime: Optional[2015-10-31T13:16:15.228Z], totalTime: Optional[PT22.921875S]]
        null,    1440, [, cmd: C:\\Windows\\System32\\spoolsv.exe, startTime: Optional[2015-10-31T13:16:15.660Z], totalTime: Optional[PT2.515625S]]
        null,    1480, [user: Optional[NT AUTHORITY\\SYSTEM], cmd: C:\\Program Files (x86)\\McAfee\\Common Framework\\FrameworkService.exe, startTime: Optional[2015-10-31T13:16:15.865Z], totalTime: Optional[PT5M43.234375S]]
        null,    1532, [user: Optional[NT AUTHORITY\\SYSTEM], cmd: C:\\Program Files (x86)\\McAfee\\VirusScan Enterprise\\vstskmgr.exe, startTime: Optional[2015-10-31T13:16:16.374Z], totalTime: Optional[PT14.4375S]]
        null,    1568, [user: Optional[NT AUTHORITY\\SYSTEM], cmd: C:\\Windows\\System32\\mfevtps.exe, startTime: Optional[2015-10-31T13:16:16.955Z], totalTime: Optional[PT1M27.203125S]]
        null,    1664, [user: Optional[NT AUTHORITY\\SYSTEM], cmd: C:\\Oracle\\agent12c\\core\\12.1.0.4.0\\bin\\nmesrvc.exe, startTime: Optional[2015-10-31T13:16:17.113Z], totalTime: Optional[PT0.609375S]]
        null,    1716, [, cmd: C:\\Program Files\\Microsoft SQL Server\\90\\Shared\\sqlwriter.exe, startTime: Optional[2015-10-31T13:16:17.223Z], totalTime: Optional[PT0.328125S]]
        null,    1884, [user: Optional[SCAAA070\\cyg_server], cmd: C:\\cygwin\\bin\\cygrunsrv.exe, startTime: Optional[2015-10-31T13:16:17.726Z], totalTime: Optional[PT0.25S]]
        null,    1932, [user: Optional[NT AUTHORITY\\SYSTEM], cmd: C:\\Windows\\System32\\svchost.exe, startTime: Optional[2015-10-31T13:16:18.352Z], totalTime: Optional[PT16.078125S]]
        null,    1960, [user: Optional[NT AUTHORITY\\SYSTEM], cmd: C:\\Program Files\\UltraVNC\\winvnc.exe, startTime: Optional[2015-10-31T13:16:18.391Z], totalTime: Optional[PT0.546875S]]
        null,    1980, [user: Optional[SCAAA070\\cyg_server], cmd: C:\\Windows\\System32\\conhost.exe, startTime: Optional[2015-10-31T13:16:18.454Z], totalTime: Optional[PT53M35.578125S]]
        null,    1996, [user: Optional[SCAAA070\\cyg_server], cmd: C:\\cygwin\\usr\\sbin\\sshd.exe, startTime: Optional[2015-10-31T13:16:18.454Z], totalTime: Optional[PT0.140625S]]
        null,    4172, [user: Optional[NT AUTHORITY\\SYSTEM], cmd: C:\\Program Files\\Common Files\\McAfee\\SystemCore\\mcshield.exe, startTime: Optional[2015-11-04T23:10:57.065Z], totalTime: Optional[PT2M51.921875S]]
        null,    5972, [, cmd: C:\\Windows\\System32\\svchost.exe, startTime: Optional[2015-10-31T13:17:09.032Z], totalTime: Optional[PT8.3125S]]
        null,    6044, [, cmd: C:\\Windows\\System32\\msdtc.exe, startTime: Optional[2015-10-31T13:19:08.903Z], totalTime: Optional[PT0.59375S]]
        null,    6460, [user: Optional[NT AUTHORITY\\SYSTEM], cmd: C:\\Windows\\System32\\winlogon.exe, startTime: Optional[2015-11-03T01:43:34.222Z], totalTime: Optional[PT0.328125S]]
        null,   11024, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\Program Files (x86)\\McAfee\\VirusScan Enterprise\\shstat.exe, startTime: Optional[2015-11-03T01:43:51.719Z], totalTime: Optional[PT0.28125S]]
        null,   13284, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\Windows\\explorer.exe, startTime: Optional[2015-11-03T01:43:36.200Z], totalTime: Optional[PT10.265625S]]
        null,   16120, []
        null,   47772, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\cygwin\\bin\\bash.exe, startTime: Optional[2015-11-17T20:22:29.250Z], totalTime: Optional[PT0.03125S]]
        null,   61788, [user: Optional[SCAAA070\\cyg_server], cmd: C:\\cygwin\\usr\\sbin\\sshd.exe, startTime: Optional[2015-11-17T20:22:28.705Z], totalTime: Optional[PT1M4.984375S]]
        null,   71548, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\cygwin\\bin\\bash.exe, startTime: Optional[2015-11-23T20:33:37.420Z], totalTime: Optional[PT0.046875S]]
        null,   80220, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\cygwin\\bin\\bash.exe, startTime: Optional[2015-11-23T20:36:53.332Z], totalTime: Optional[PT0.03125S]]
        null,   82536, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\cygwin\\bin\\sh.exe, startTime: Optional[2015-11-23T20:36:53.753Z], totalTime: Optional[PT0.015625S]]
         616,   27336, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\Windows\\System32\\taskhostex.exe, startTime: Optional[2015-11-03T01:43:35.886Z], totalTime: Optional[PT0.28125S]]
         816,     824, []
         816,     876, [user: Optional[NT AUTHORITY\\SYSTEM], cmd: C:\\Windows\\System32\\winlogon.exe, startTime: Optional[2015-10-31T13:16:05.188Z], totalTime: Optional[PT0.65625S]]
         832,     920, []
         832,     928, [user: Optional[NT AUTHORITY\\SYSTEM], cmd: C:\\Windows\\System32\\lsass.exe, startTime: Optional[2015-10-31T13:16:08.687Z], totalTime: Optional[PT20M50.890625S]]
         876,     676, [user: Optional[NT AUTHORITY\\SYSTEM], cmd: C:\\Windows\\System32\\LogonUI.exe, startTime: Optional[2015-10-31T13:16:13.315Z], totalTime: Optional[PT0.390625S]]
         876,     720, [, cmd: C:\\Windows\\System32\\dwm.exe, startTime: Optional[2015-10-31T13:16:13.394Z], totalTime: Optional[PT0.15625S]]
        1000,    1796, [user: Optional[NT AUTHORITY\\SYSTEM], cmd: C:\\Program Files (x86)\\McAfee\\Common Framework\\naPrdMgr.exe, startTime: Optional[2015-10-31T13:16:17.395Z], totalTime: Optional[PT1M46.515625S]]
        1000,    4928, [user: Optional[NT AUTHORITY\\SYSTEM], cmd: C:\\Windows\\System32\\wbem\\WmiPrvSE.exe, startTime: Optional[2015-10-31T13:16:56.625Z], totalTime: Optional[PT26M43.0625S]]
        1532,    1580, [user: Optional[NT AUTHORITY\\SYSTEM], cmd: C:\\Program Files (x86)\\McAfee\\VirusScan Enterprise\\mfeann.exe, startTime: Optional[2015-10-31T13:16:17.019Z], totalTime: Optional[PT0.765625S]]
        1580,    1616, [user: Optional[NT AUTHORITY\\SYSTEM], cmd: C:\\Windows\\System32\\conhost.exe, startTime: Optional[2015-10-31T13:16:17.050Z], totalTime: Optional[PT0.328125S]]
        1664,    1684, [user: Optional[NT AUTHORITY\\SYSTEM], cmd: C:\\Windows\\System32\\conhost.exe, startTime: Optional[2015-10-31T13:16:17.176Z], totalTime: Optional[PT1.4375S]]
        1664,    4784, [user: Optional[NT AUTHORITY\\SYSTEM], cmd: C:\\Windows\\System32\\cmd.exe, startTime: Optional[2015-10-31T13:16:33.269Z], totalTime: Optional[PT0.25S]]
        1960,    2900, [user: Optional[NT AUTHORITY\\SYSTEM], cmd: C:\\Program Files\\UltraVNC\\winvnc.exe, startTime: Optional[2015-10-31T13:16:23.686Z], totalTime: Optional[PT0.46875S]]
        4784,    4800, [user: Optional[NT AUTHORITY\\SYSTEM], cmd: C:\\Oracle\\agent12c\\core\\12.1.0.4.0\\perl\\bin\\perl.exe, startTime: Optional[2015-10-31T13:16:33.427Z], totalTime: Optional[PT1.609375S]]
        4800,    4672, [user: Optional[NT AUTHORITY\\SYSTEM], cmd: C:\\Oracle\\agent12c\\core\\12.1.0.4.0\\jdk\\bin\\java.exe, startTime: Optional[2015-10-31T13:16:44.344Z], totalTime: Optional[PT13M25.984375S]]
        5972,    8572, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\Windows\\System32\\rdpclip.exe, startTime: Optional[2015-11-03T01:43:35.978Z], totalTime: Optional[PT0.484375S]]
        6460,   22852, [, cmd: C:\\Windows\\System32\\dwm.exe, startTime: Optional[2015-11-03T01:43:35.104Z], totalTime: Optional[PT0.796875S]]
       46768,   74624, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\cygwin\\bin\\sh.exe, startTime: Optional[2015-11-23T20:33:37.378Z], totalTime: Optional[PT0S]]
       47772,   64912, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\cygwin\\bin\\bash.exe, startTime: Optional[2015-11-17T20:22:29.263Z], totalTime: Optional[PT0S]]
       51216,   79908, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\jenkins\\workspace\\9-dev-tier2-windows-i586\\mach5\\jdkbuildwrapper\\.data\\unpacked\\jpg\\infra\\builddeps\\jdk-8-windows-i586\\1.0\\jdk1.8.0\\bin\\java.exe, startTime: Optional[2015-11-23T20:36:54.634Z], totalTime: Optional[PT1M10.6875S]]
       64912,   46768, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\java\\jdk1.8.0_25\\bin\\java.exe, startTime: Optional[2015-11-17T20:22:29.270Z], totalTime: Optional[PT2H28M59.875S]]
       76752,   79468, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\jenkins\\workspace\\9-dev-tier2-windows-i586\\build\\jdk\\bin\\java.exe, startTime: Optional[2015-11-23T20:37:43.628Z], totalTime: Optional[PT0.140625S]]
       79468,     816, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\jenkins\\workspace\\9-dev-tier2-windows-i586\\build\\jdk\\bin\\java.exe, startTime: Optional[2015-11-23T20:37:43.773Z], totalTime: Optional[PT0.109375S]]
       79468,   70068, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\jenkins\\workspace\\9-dev-tier2-windows-i586\\build\\jdk\\bin\\java.exe, startTime: Optional[2015-11-23T20:37:43.761Z], totalTime: Optional[PT0.140625S]]
       79468,   77308, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\jenkins\\workspace\\9-dev-tier2-windows-i586\\build\\jdk\\bin\\java.exe, startTime: Optional[2015-11-23T20:37:43.777Z], totalTime: Optional[PT0.125S]]
       79908,    4140, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\jenkins\\workspace\\9-dev-tier2-windows-i586\\build\\jdk\\bin\\java.exe, startTime: Optional[2015-11-23T20:37:40.371Z], totalTime: Optional[PT4.796875S]]
       79908,   20264, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\jenkins\\workspace\\9-dev-tier2-windows-i586\\build\\jdk\\bin\\java.exe, startTime: Optional[2015-11-23T20:37:40.473Z], totalTime: Optional[PT4.484375S]]
       79908,   60008, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\jenkins\\workspace\\9-dev-tier2-windows-i586\\build\\jdk\\bin\\java.exe, startTime: Optional[2015-11-23T20:37:43.336Z], totalTime: Optional[PT1.46875S]]
       79908,   62716, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\jenkins\\workspace\\9-dev-tier2-windows-i586\\build\\jdk\\bin\\java.exe, startTime: Optional[2015-11-23T20:37:42.076Z], totalTime: Optional[PT2.71875S]]
       79908,   63144, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\jenkins\\workspace\\9-dev-tier2-windows-i586\\build\\jdk\\bin\\java.exe, startTime: Optional[2015-11-23T20:37:40.790Z], totalTime: Optional[PT1.640625S]]
       79908,   69452, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\jenkins\\workspace\\9-dev-tier2-windows-i586\\build\\jdk\\bin\\java.exe, startTime: Optional[2015-11-23T20:37:40.897Z], totalTime: Optional[PT2.703125S]]
       79908,   71716, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\jenkins\\workspace\\9-dev-tier2-windows-i586\\build\\jdk\\bin\\java.exe, startTime: Optional[2015-11-23T20:37:41.112Z], totalTime: Optional[PT4S]]
       79908,   75812, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\jenkins\\workspace\\9-dev-tier2-windows-i586\\build\\jdk\\bin\\java.exe, startTime: Optional[2015-11-23T20:37:40.574Z], totalTime: Optional[PT1.078125S]]
       79908,   75892, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\jenkins\\workspace\\9-dev-tier2-windows-i586\\build\\jdk\\bin\\java.exe, startTime: Optional[2015-11-23T20:37:43.349Z], totalTime: Optional[PT1.390625S]]
       79908,   76752, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\jenkins\\workspace\\9-dev-tier2-windows-i586\\build\\jdk\\bin\\java.exe, startTime: Optional[2015-11-23T20:37:42.955Z], totalTime: Optional[PT1.046875S]]
       79908,   77916, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\jenkins\\workspace\\9-dev-tier2-windows-i586\\build\\jdk\\bin\\java.exe, startTime: Optional[2015-11-23T20:37:41.007Z], totalTime: Optional[PT2.65625S]]
       79908,   79052, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\jenkins\\workspace\\9-dev-tier2-windows-i586\\build\\jdk\\bin\\java.exe, startTime: Optional[2015-11-23T20:37:40.265Z], totalTime: Optional[PT4.59375S]]
       79908,   85436, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\jenkins\\workspace\\9-dev-tier2-windows-i586\\build\\jdk\\bin\\java.exe, startTime: Optional[2015-11-23T20:37:40.680Z], totalTime: Optional[PT4.15625S]]
       82536,   51216, [user: Optional[SCAAA070\\mach5-one], cmd: C:\\cygwin\\bin\\sh.exe, startTime: Optional[2015-11-23T20:36:54.627Z], totalTime: Optional[PT0S]]
test TreeTest.test4(): success
Spawning 2 x 2 x 2 processes, pid: 75732
 descendants:  [73468, 58492, 59708, 30568, 55584, 51372, 69996, 72780, 71900, 80272, 81276, 84484, 86392, 25508]
Duration: PT0.6034867S
test TreeTest.test5(): success

===============================================
java/lang/ProcessHandle/TreeTest.java
Total tests run: 5, Failures: 1, Skips: 0
===============================================

----------System.err:(14/810)----------
java.lang.Exception: failures: 1
	at com.sun.javatest.regtest.TestNGAction$TestNGRunner.main(TestNGAction.java:166)
	at com.sun.javatest.regtest.TestNGAction$TestNGRunner.main(TestNGAction.java:147)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:520)
	at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:92)
	at java.lang.Thread.run(Thread.java:747)

JavaTest Message: Test threw exception: java.lang.Exception: failures: 1
JavaTest Message: shutting down test