Uploaded image for project: 'JDK'
  1. JDK
  2. JDK-8150346

java/lang/ProcessHandle/Infotest.java failed: startTime after process spawn completed

XMLWordPrintable

    • b109
    • generic
    • windows

      This has been observed twice on the same Windows machine. It is intermittent. Only found twice similar failures in the jdk 9 test history

      Please refer to the error below:
      ----------messages:(3/110)----------
      command: testng InfoTest
      reason: User specified action: run testng InfoTest
      elapsed time (seconds): 1.313
      ----------System.out:(88/6010)*----------
      [TestNG] Running:
        java/lang/ProcessHandle/Basic.java

      Note: when run in samevm mode the cputime of the test runner is included.
       info: [user: Optional[SCA00BEP\\aurora], cmd: C:\\Users\\aurora\\CommonData\\jdk\\bin\\java.exe, startTime: Optional[2016-02-19T07:33:41.679Z], totalTime: Optional[PT1.078125S]]
      test InfoTest.test1(): success
       info: [user: Optional[SCA00BEP\\aurora], cmd: C:\\Users\\aurora\\CommonData\\jdk\\bin\\java.exe, startTime: Optional[2016-02-19T07:33:42.453Z], totalTime: Optional[PT0.421875S]]
       info.totalCPU: 421875000, childCpuTime: 421875000, diff: 0
      destroyProcessTree destroyForcibly
      pid 23332, alive: true; parent: Optional[19592], [user: Optional[SCA00BEP\\aurora], cmd: C:\\Users\\aurora\\CommonData\\jdk\\bin\\java.exe, startTime: Optional[2016-02-19T07:33:42.453Z], totalTime: Optional[PT0.421875S]]
      test InfoTest.test2(): failure
      java.lang.AssertionError: startTime after process spawn completed2016-02-19T07:33:42.453Z + > 2016-02-19T07:33:42.448140200Z 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 InfoTest.test2(InfoTest.java:254)
      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:804)
       info: [user: Optional[SCA00BEP\\aurora], cmd: C:\\cygwin\\bin\\sleep.exe, startTime: Optional[2016-02-19T07:33:42.836Z], totalTime: Optional[PT0S]]
       info: [user: Optional[SCA00BEP\\aurora], cmd: C:\\cygwin\\bin\\sleep.exe, startTime: Optional[2016-02-19T07:33:42.844Z], totalTime: Optional[PT0S]]
      test InfoTest.test3(): success
       total1 vs. mbean: PT1.265625S, getProcessCpuTime: PT1.265625S, diff: PT0S
       total2 vs. mbean: PT1.265625S, getProcessCpuTime: PT1.265625S, diff: PT0S
      test InfoTest.test4(): success
       infoTotal: 1265625000, beanCpu: 1265625000, diff: 0
       infoTotal: 1265625000, beanCpu: 1265625000, diff: 0
       infoTotal: 1265625000, beanCpu: 1265625000, diff: 0
       infoTotal: 1265625000, beanCpu: 1265625000, diff: 0
       infoTotal: 1265625000, beanCpu: 1265625000, diff: 0
       infoTotal: 1265625000, beanCpu: 1265625000, diff: 0
       infoTotal: 1265625000, beanCpu: 1265625000, diff: 0
       infoTotal: 1265625000, beanCpu: 1265625000, diff: 0
       infoTotal: 1265625000, beanCpu: 1265625000, diff: 0
       infoTotal: 1265625000, beanCpu: 1265625000, diff: 0
       infoTotal: 1265625000, beanCpu: 1265625000, diff: 0
       infoTotal: 1296875000, beanCpu: 1296875000, diff: 0
       infoTotal: 1296875000, beanCpu: 1296875000, diff: 0
       infoTotal: 1296875000, beanCpu: 1296875000, diff: 0
       infoTotal: 1296875000, beanCpu: 1296875000, diff: 0
       infoTotal: 1296875000, beanCpu: 1296875000, diff: 0
       infoTotal: 1296875000, beanCpu: 1296875000, diff: 0
       infoTotal: 1296875000, beanCpu: 1296875000, diff: 0
       infoTotal: 1296875000, beanCpu: 1296875000, diff: 0
       infoTotal: 1296875000, beanCpu: 1296875000, diff: 0
       infoTotal: 1296875000, beanCpu: 1296875000, diff: 0
       infoTotal: 1296875000, beanCpu: 1296875000, diff: 0
       infoTotal: 1296875000, beanCpu: 1296875000, diff: 0
       infoTotal: 1296875000, beanCpu: 1296875000, diff: 0
       infoTotal: 1296875000, beanCpu: 1296875000, diff: 0
       infoTotal: 1296875000, beanCpu: 1296875000, diff: 0
       infoTotal: 1296875000, beanCpu: 1296875000, diff: 0
       infoTotal: 1296875000, beanCpu: 1296875000, diff: 0
       infoTotal: 1296875000, beanCpu: 1296875000, diff: 0
       infoTotal: 1296875000, beanCpu: 1296875000, diff: 0
      test InfoTest.test5(): success

      ===============================================
      java/lang/ProcessHandle/Basic.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:804)

            rriggs Roger Riggs
            xiaofeya Xiaofeng Yang
            Votes:
            0 Vote for this issue
            Watchers:
            4 Start watching this issue

              Created:
              Updated:
              Resolved: