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

Test java/foreign/TestHandshake.java fails intermittently

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Resolved
    • Priority: P4
    • Resolution: Fixed
    • Affects Version/s: 16, 17
    • Fix Version/s: 17
    • Component/s: core-libs
    • Resolved In Build:
      b13
    • OS:
      windows

      Description

      This test has been seen on SBR runs - but the logs give little by way of what the actual issue might be !!!

      ----------System.out:(2320/97758)----------
      [TestNG] Running:
        java/foreign/TestHandshake.java

      ITERATION 0
      Starting handshaker with delay set to 199 millis
      Accessor #21 suspending - delay (ms): 459
      Accessor #41 suspending - delay (ms): 453
      Accessor #1 suspending - delay (ms): 438
      Accessor #35 suspending - delay (ms): 459
      Accessor #32 suspending - delay (ms): 439
      Accessor #5 suspending - delay (ms): 438
      Accessor #28 suspending - delay (ms): 443
      Accessor #20 suspending - delay (ms): 459
      Accessor #39 suspending - delay (ms): 442
      Accessor #50 suspending - delay (ms): 443
      Accessor #29 suspending - delay (ms): 453
      Accessor #43 suspending - delay (ms): 443
      Accessor #37 suspending - delay (ms): 452
      Accessor #27 suspending - delay (ms): 459
      Accessor #46 suspending - delay (ms): 443
      Accessor #31 suspending - delay (ms): 459
      Accessor #34 suspending - delay (ms): 459
      Accessor #40 suspending - delay (ms): 438
      Accessor #11 suspending - delay (ms): 459
      Accessor #18 suspending - delay (ms): 443
      Accessor #0 suspending - delay (ms): 439
      Accessor #7 suspending - delay (ms): 459
      Accessor #26 suspending - delay (ms): 443
      Accessor #48 suspending - delay (ms): 459
      Accessor #25 suspending - delay (ms): 443
      Accessor #2 suspending - delay (ms): 438
      Accessor #12 suspending - delay (ms): 439
      Accessor #38 suspending - delay (ms): 443
      Accessor #17 suspending - delay (ms): 438
      Accessor #49 suspending - delay (ms): 440
      Accessor #22 suspending - delay (ms): 453
      Accessor #47 suspending - delay (ms): 453
      Accessor #23 suspending - delay (ms): 443
      Accessor #16 suspending - delay (ms): 440
      Accessor #44 suspending - delay (ms): 443
      Accessor #3 suspending - delay (ms): 438
      Accessor #9 suspending - delay (ms): 438
      Accessor #8 suspending - delay (ms): 438
      Accessor #40 resuming - delay (ms): 478
      Accessor #30 suspending - delay (ms): 438
      Accessor #14 suspending - delay (ms): 438
      Accessor #15 suspending - delay (ms): 438
      Accessor #19 suspending - delay (ms): 438
      Accessor #36 suspending - delay (ms): 443
      Accessor #24 suspending - delay (ms): 440
      Accessor #6 suspending - delay (ms): 459
      Accessor #10 suspending - delay (ms): 438
      Accessor #4 suspending - delay (ms): 438
      Accessor #42 suspending - delay (ms): 453
      Accessor #13 suspending - delay (ms): 438
      Accessor #40 terminated - delay (ms): 480
      Accessor #51 suspending - delay (ms): 459
      Accessor #43 resuming - delay (ms): 482
      Accessor #43 terminated - delay (ms): 482
      Accessor #44 resuming - delay (ms): 482
      Accessor #44 terminated - delay (ms): 482
      Accessor #45 suspending - delay (ms): 438
      Accessor #33 suspending - delay (ms): 438
      Segment closed - delay (ms): 477

      <deleted log output>

      Accessor #7 resuming - delay (ms): 606
      Accessor #7 terminated - delay (ms): 606
      Accessor #35 resuming - delay (ms): 606
      Accessor #35 terminated - delay (ms): 606
      Accessor #37 resuming - delay (ms): 608
      Accessor #37 t

      ...
      Output overflow:
      JT Harness has limited the test output to the text
      at the beginning and the end, so that you can see how the
      test began, and how it completed.

      If you need to see more of the output from the test,
      set the system property javatest.maxOutputSize to a higher
      value. The current value is 100000
      ...

      d - delay (ms): 623
      Accessor #45 resuming - delay (ms): 623
      Accessor #45 terminated - delay (ms): 623
      Accessor #22 resuming - delay (ms): 624
      Accessor #22 terminated - delay (ms): 624
      Accessor #23 resuming - delay (ms): 627
      Accessor #23 terminated - delay (ms): 627
      Accessor #7 resuming - delay (ms): 629

      <deleted line of log output>

      Accessor #38 resuming - delay (ms): 1474
      Accessor #38 terminated - delay (ms): 1474
      Accessor #0 resuming - delay (ms): 1485
      Accessor #0 terminated - delay (ms): 1485
      Accessor #16 resuming - delay (ms): 1493
      Accessor #16 terminated - delay (ms): 1493
      Accessor #41 resuming - delay (ms): 1498
      Accessor #41 terminated - delay (ms): 1498
      Accessor #43 resuming - delay (ms): 1500
      Accessor #43 terminated - delay (ms): 1500
      Accessor #19 resuming - delay (ms): 1500
      Accessor #19 terminated - delay (ms): 1500
      test TestHandshake.testHandshake("BufferHandleAccessor", TestHandshake$$Lambda$36/0x0000000800c22e88@7ce830a3): success

      ===============================================
      java/foreign/TestHandshake.java
      Total tests run: 6, Failures: 0, Skips: 0
      ===============================================


      ===============================================
      java/foreign/TestHandshake.java
      Total tests run: 6, Failures: 0, Skips: 0
      ===============================================

      ----------System.err:(2/73)----------
      WARNING: Using incubator modules: jdk.incubator.foreign
      STATUS:Passed.
      ----------rerun:(42/3473)*----------
      cd 'C:\\Users\\opc\\sandbox_keepme\\results\\run_7\\tier1\\JTwork\\test_jdk_0\\scratch\\0' && \\
      JDK8_HOME=C:/users/opc/sandbox_keepme/jdk8 \\
      JTREG_HOME=C:/users/opc/sandbox_keepme/jtreg \\
      JTREG_JAVA=C:/users/opc/sandbox_keepme/stable_jdk/bin/java \\
      JTREG_SHOWAGENT=true \\
      PATH='C:\\cygwin64\\usr\\local\\bin;C:\\cygwin64\\bin;C:\\Windows\\system32;C:\\Windows;C:\\Windows\\System32\\Wbem;C:\\Windows\\System32\\WindowsPowerShell\\v1.0;C:\\Users\\opc\\AppData\\Local\\Microsoft\\WindowsApps' \\
      SystemDrive=C: \\
      SystemRoot='C:\\Windows' \\
      TEMP='C:\\cygwin64\\tmp' \\
      TMP='C:\\cygwin64\\tmp' \\
      TZ=Etc/GMT \\
      windir='C:\\Windows' \\
      CLASSPATH='C:\\Users\\opc\\sandbox_keepme\\results\\run_7\\tier1\\JTwork\\test_jdk_0\\classes\\2\\java\\foreign\\TestHandshake.d;C:\\Users\\opc\\sandbox_keepme\\testbase\\open\\test\\jdk\\java\\foreign;C:\\Users\\opc\\sandbox_keepme\\jtreg\\lib\\testng.jar;C:\\Users\\opc\\sandbox_keepme\\jtreg\\lib\\javatest.jar;C:\\Users\\opc\\sandbox_keepme\\jtreg\\lib\\jtreg.jar' \\
          'C:\\users\\opc\\sandbox_keepme\\jdk\\bin\\java' \\
              -Dtest.vm.opts='-ea -esa -Xmx512m -XX:MaxRAMPercentage=1 -Dtest.nss.lib.paths=C:/users/opc/sandbox_keepme/nsslib/' \\
              -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-XX:MaxRAMPercentage=1 -J-Dtest.nss.lib.paths=C:/users/opc/sandbox_keepme/nsslib/' \\
              -Dtest.compiler.opts= \\
              -Dtest.java.opts= \\
              -Dtest.jdk='C:\\users\\opc\\sandbox_keepme\\jdk' \\
              -Dcompile.jdk='C:\\users\\opc\\sandbox_keepme\\jdk' \\
              -Dtest.timeout.factor=10.0 \\
              -Dtest.nativepath='C:\\users\\opc\\sandbox_keepme\\testBundle\\jdk\\jtreg\\native' \\
              -Dtest.root='C:\\Users\\opc\\sandbox_keepme\\testbase\\open\\test\\jdk' \\
              -Dtest.name=java/foreign/TestHandshake.java \\
              -Dtest.file='C:\\Users\\opc\\sandbox_keepme\\testbase\\open\\test\\jdk\\java\\foreign\\TestHandshake.java' \\
              -Dtest.src='C:\\Users\\opc\\sandbox_keepme\\testbase\\open\\test\\jdk\\java\\foreign' \\
              -Dtest.src.path='C:\\Users\\opc\\sandbox_keepme\\testbase\\open\\test\\jdk\\java\\foreign' \\
              -Dtest.classes='C:\\Users\\opc\\sandbox_keepme\\results\\run_7\\tier1\\JTwork\\test_jdk_0\\classes\\2\\java\\foreign\\TestHandshake.d' \\
              -Dtest.class.path='C:\\Users\\opc\\sandbox_keepme\\results\\run_7\\tier1\\JTwork\\test_jdk_0\\classes\\2\\java\\foreign\\TestHandshake.d' \\
              -Dtest.class.path.prefix='C:\\Users\\opc\\sandbox_keepme\\results\\run_7\\tier1\\JTwork\\test_jdk_0\\classes\\2\\java\\foreign\\TestHandshake.d;C:\\Users\\opc\\sandbox_keepme\\testbase\\open\\test\\jdk\\java\\foreign' \\
              -Dtest.modules='jdk.incubator.foreign java.base/jdk.internal.vm.annotation java.base/jdk.internal.misc' \\
              --add-modules jdk.incubator.foreign,java.base \\
              --add-exports java.base/jdk.internal.vm.annotation=ALL-UNNAMED \\
              --add-exports java.base/jdk.internal.misc=ALL-UNNAMED \\
              -ea \\
              -esa \\
              -Xmx512m \\
              -XX:MaxRAMPercentage=1 \\
              -Dtest.nss.lib.paths=C:/users/opc/sandbox_keepme/nsslib/ \\
              '-Djava.library.path=C:\\users\\opc\\sandbox_keepme\\testBundle\\jdk\\jtreg\\native' \\
              -Xint \\
              com.sun.javatest.regtest.agent.MainWrapper 'C:\\Users\\opc\\sandbox_keepme\\results\\run_7\\tier1\\JTwork\\test_jdk_0\\java\\foreign\\TestHandshake.d\\testng.1.jta' java/foreign/TestHandshake.java false TestHandshake
      result: Passed. Execution successful

      #section:build
      ----------messages:(3/99)----------
      command: build TestHandshake
      reason: Named class compiled on demand
      elapsed time (seconds): 0.0
      result: Passed. All files up to date

      #section:testng
      ----------messages:(5/411)----------
      command: testng -XX:TieredStopAtLevel=1 TestHandshake
      reason: User specified action: run testng/othervm -XX:TieredStopAtLevel=1 TestHandshake
      Mode: othervm [/othervm specified]
      Additional options from @modules: --add-modules jdk.incubator.foreign,java.base --add-exports java.base/jdk.internal.vm.annotation=ALL-UNNAMED --add-exports java.base/jdk.internal.misc=ALL-UNNAMED
      elapsed time (seconds): 40.963
      ----------configuration:(5/198)----------
      Boot Layer
        add modules: jdk.incubator.foreign java.base
        add exports: java.base/jdk.internal.misc ALL-UNNAMED
                     java.base/jdk.internal.vm.annotation ALL-UNNAMED

      ----------System.out:(1649/69752)----------
      [TestNG] Running:
        java/foreign/TestHandshake.java

      ITERATION 0
      Starting handshaker with delay set to 290 millis
      Segment closed - delay (ms): 1306
      Accessor #45 suspending - delay (ms): 1302
      Accessor #9 suspending - delay (ms): 1300
      Accessor #25 suspending - delay (ms): 1299
      Accessor #48 suspending - delay (ms): 1302
      Accessor #30 suspending - delay (ms): 1302
      Accessor #15 suspending - delay (ms): 1302
      Accessor #7 suspending - delay (ms): 1299
      Accessor #38 suspending - delay (ms): 1302
      Accessor #10 suspending - delay (ms): 1302
      Accessor #37 suspending - delay (ms): 1299
      Accessor #22 suspending - delay (ms): 1302
      Accessor #39 suspending - delay (ms): 1302
      Accessor #34 suspending - delay (ms): 1302
      Accessor #17 suspending - delay (ms): 1299
      Accessor #40 suspending - delay (ms): 1300
      Accessor #33 suspending - delay (ms): 1299
      Accessor #29 suspending - delay (ms): 1302
      Accessor #13 suspending - delay (ms): 1300
      Accessor #36 suspending - delay (ms): 1302

      <deleted line>

      Accessor #5 terminated - delay (ms): 434
      Accessor #26 resuming - delay (ms): 440
      Accessor #26 terminated - delay (ms): 440
      Accessor #31 resuming - delay (ms): 443
      Accessor #31 terminated - delay (ms): 443
      Accessor #45 resuming - delay (ms): 444
      Accessor #45 terminated - delay (ms): 444
      Accessor #51 resuming - delay (ms): 444
      Accessor #51 terminated - delay (ms): 444
      Accessor #12 resuming - delay (ms): 450
      Accessor #12 terminated - delay (ms): 450
      Accessor #11 resuming - delay (ms): 450
      Accessor #11 terminated - delay (ms): 450
      test TestHandshake.testHandshake("BufferHandleAccessor", TestHandshake$$Lambda$37/0x0000000800c23618@7cc898d4): success

      ===============================================
      java/foreign/TestHandshake.java
      Total tests run: 6, Failures: 1, Skips: 0
      ===============================================

      ----------System.err:(15/929)----------
      WARNING: Using incubator modules: jdk.incubator.foreign
      java.lang.Exception: failures: 1
      at com.sun.javatest.regtest.agent.TestNGRunner.main(TestNGRunner.java:96)
      at com.sun.javatest.regtest.agent.TestNGRunner.main(TestNGRunner.java:54)
      at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:78)
      at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      at java.base/java.lang.reflect.Method.invoke(Method.java:567)
      at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:127)
      at java.base/java.lang.Thread.run(Thread.java:831)

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

      STATUS:Failed.`main' threw exception: java.lang.Exception: failures: 1
      ----------rerun:(42/3491)*----------
      cd 'C:\\Users\\opc\\sandbox_keepme\\results\\run_7\\tier1\\JTwork\\test_jdk_0\\scratch\\0' && \\
      JDK8_HOME=C:/users/opc/sandbox_keepme/jdk8 \\
      JTREG_HOME=C:/users/opc/sandbox_keepme/jtreg \\
      JTREG_JAVA=C:/users/opc/sandbox_keepme/stable_jdk/bin/java \\
      JTREG_SHOWAGENT=true \\
      PATH='C:\\cygwin64\\usr\\local\\bin;C:\\cygwin64\\bin;C:\\Windows\\system32;C:\\Windows;C:\\Windows\\System32\\Wbem;C:\\Windows\\System32\\WindowsPowerShell\\v1.0;C:\\Users\\opc\\AppData\\Local\\Microsoft\\WindowsApps' \\
      SystemDrive=C: \\
      SystemRoot='C:\\Windows' \\
      TEMP='C:\\cygwin64\\tmp' \\
      TMP='C:\\cygwin64\\tmp' \\
      TZ=Etc/GMT \\
      windir='C:\\Windows' \\
      CLASSPATH='C:\\Users\\opc\\sandbox_keepme\\results\\run_7\\tier1\\JTwork\\test_jdk_0\\classes\\2\\java\\foreign\\TestHandshake.d;C:\\Users\\opc\\sandbox_keepme\\testbase\\open\\test\\jdk\\java\\foreign;C:\\Users\\opc\\sandbox_keepme\\jtreg\\lib\\testng.jar;C:\\Users\\opc\\sandbox_keepme\\jtreg\\lib\\javatest.jar;C:\\Users\\opc\\sandbox_keepme\\jtreg\\lib\\jtreg.jar' \\
          'C:\\users\\opc\\sandbox_keepme\\jdk\\bin\\java' \\
              -Dtest.vm.opts='-ea -esa -Xmx512m -XX:MaxRAMPercentage=1 -Dtest.nss.lib.paths=C:/users/opc/sandbox_keepme/nsslib/' \\
              -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-XX:MaxRAMPercentage=1 -J-Dtest.nss.lib.paths=C:/users/opc/sandbox_keepme/nsslib/' \\
              -Dtest.compiler.opts= \\
              -Dtest.java.opts= \\
              -Dtest.jdk='C:\\users\\opc\\sandbox_keepme\\jdk' \\
              -Dcompile.jdk='C:\\users\\opc\\sandbox_keepme\\jdk' \\
              -Dtest.timeout.factor=10.0 \\
              -Dtest.nativepath='C:\\users\\opc\\sandbox_keepme\\testBundle\\jdk\\jtreg\\native' \\
              -Dtest.root='C:\\Users\\opc\\sandbox_keepme\\testbase\\open\\test\\jdk' \\
              -Dtest.name=java/foreign/TestHandshake.java \\
              -Dtest.file='C:\\Users\\opc\\sandbox_keepme\\testbase\\open\\test\\jdk\\java\\foreign\\TestHandshake.java' \\
              -Dtest.src='C:\\Users\\opc\\sandbox_keepme\\testbase\\open\\test\\jdk\\java\\foreign' \\
              -Dtest.src.path='C:\\Users\\opc\\sandbox_keepme\\testbase\\open\\test\\jdk\\java\\foreign' \\
              -Dtest.classes='C:\\Users\\opc\\sandbox_keepme\\results\\run_7\\tier1\\JTwork\\test_jdk_0\\classes\\2\\java\\foreign\\TestHandshake.d' \\
              -Dtest.class.path='C:\\Users\\opc\\sandbox_keepme\\results\\run_7\\tier1\\JTwork\\test_jdk_0\\classes\\2\\java\\foreign\\TestHandshake.d' \\
              -Dtest.class.path.prefix='C:\\Users\\opc\\sandbox_keepme\\results\\run_7\\tier1\\JTwork\\test_jdk_0\\classes\\2\\java\\foreign\\TestHandshake.d;C:\\Users\\opc\\sandbox_keepme\\testbase\\open\\test\\jdk\\java\\foreign' \\
              -Dtest.modules='jdk.incubator.foreign java.base/jdk.internal.vm.annotation java.base/jdk.internal.misc' \\
              --add-modules jdk.incubator.foreign,java.base \\
              --add-exports java.base/jdk.internal.vm.annotation=ALL-UNNAMED \\
              --add-exports java.base/jdk.internal.misc=ALL-UNNAMED \\
              -ea \\
              -esa \\
              -Xmx512m \\
              -XX:MaxRAMPercentage=1 \\
              -Dtest.nss.lib.paths=C:/users/opc/sandbox_keepme/nsslib/ \\
              '-Djava.library.path=C:\\users\\opc\\sandbox_keepme\\testBundle\\jdk\\jtreg\\native' \\
              -XX:TieredStopAtLevel=1 \\
              com.sun.javatest.regtest.agent.MainWrapper 'C:\\Users\\opc\\sandbox_keepme\\results\\run_7\\tier1\\JTwork\\test_jdk_0\\java\\foreign\\TestHandshake.d\\testng.2.jta' java/foreign/TestHandshake.java false TestHandshake
      result: Failed. Execution failed: `main' threw exception: java.lang.Exception: failures: 1


      test result: Failed. Execution failed: `main' threw exception: java.lang.Exception: failures: 1

        Attachments

          Issue Links

            Activity

              People

              Assignee:
              mcimadamore Maurizio Cimadamore
              Reporter:
              msheppar Mark Sheppard
              Votes:
              0 Vote for this issue
              Watchers:
              5 Start watching this issue

                Dates

                Created:
                Updated:
                Resolved: