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

sun/security/tools/jarsigner/multiRelease/MVJarSigningTest.java timed out

XMLWordPrintable

      The following test timed out in the JDK17 CI in the UT-enabled pool:

      sun/security/tools/jarsigner/multiRelease/MVJarSigningTest.java

      Here's a snippet from the log file:

      ----------System.out:(117/8763)----------
      Command line: [/opt/mach5/mesos/work_dir/jib-master/install/jdk-17+20-1757/linux-x64.jdk/jdk-17/bin/jar cf MV.jar -C classes/base . --release 9 -C classes/v9 . --release 10 -C classes/v10 .]
      [2021-04-30T05:26:01.948756892Z] Gathering output for process 22679
      [2021-04-30T05:26:02.237492481Z] Waiting for completion for process 22679
      [2021-04-30T05:26:02.237668603Z] Waiting for completion finished for process 22679
      Output and diagnostic info for process 22679 was saved into 'pid-22679-output.log'

      [2021-04-30T05:26:02.239945103Z] Waiting for completion for process 22679
      [2021-04-30T05:26:02.240075720Z] Waiting for completion finished for process 22679
      Command line: [/opt/mach5/mesos/work_dir/jib-master/install/jdk-17+20-1757/linux-x64.jdk/jdk-17/bin/keytool -J-Duser.language=en -J-Duser.country=US -genkey -keyalg dsa -alias JavaTest -keystore keystore.jks -keypass changeit -dname cn=sample -storepass changeit]
      [2021-04-30T05:26:02.243957243Z] Gathering output for process 22747
      [2021-04-30T05:26:03.239646169Z] Waiting for completion for process 22747
      [2021-04-30T05:26:03.240368904Z] Waiting for completion finished for process 22747
      Output and diagnostic info for process 22747 was saved into 'pid-22747-output.log'
      Generating 2,048 bit DSA key pair and self-signed certificate (SHA256withDSA) with a validity of 90 days
      for: CN=sample

      [2021-04-30T05:26:03.241421683Z] Waiting for completion for process 22747
      [2021-04-30T05:26:03.241598087Z] Waiting for completion finished for process 22747
      Command line: [/opt/mach5/mesos/work_dir/jib-master/install/jdk-17+20-1757/linux-x64.jdk/jdk-17/bin/jarsigner -J-Duser.language=en -J-Duser.country=US -keystore keystore.jks -storepass changeit -keypass changeit -verbose -signedjar Signed.jar MV.jar JavaTest]
      [2021-04-30T05:26:03.245208336Z] Gathering output for process 22846
      [2021-04-30T05:26:04.156549051Z] Waiting for completion for process 22846
      [2021-04-30T05:26:04.156691921Z] Waiting for completion finished for process 22846
      Output and diagnostic info for process 22846 was saved into 'pid-22846-output.log'
       updating: META-INF/MANIFEST.MF
         adding: META-INF/JAVATEST.SF
         adding: META-INF/JAVATEST.DSA
        signing: META-INF/versions/9/version/Version.class
        signing: META-INF/versions/10/version/Version.class
         adding: version/
        signing: version/Main.class
        signing: version/Version.class

      >>> Signer
          X.509, CN=sample
          Signature algorithm: SHA256withDSA, 2048-bit key
          [trusted certificate]

      jar signed.

      Warning:
      The signer's certificate is self-signed.

      [2021-04-30T05:26:04.157667644Z] Waiting for completion for process 22846
      [2021-04-30T05:26:04.158105461Z] Waiting for completion finished for process 22846
      Command line: [/opt/mach5/mesos/work_dir/jib-master/install/jdk-17+20-1757/linux-x64.jdk/jdk-17/bin/jarsigner -J-Duser.language=en -J-Duser.country=US -keystore keystore.jks -storepass changeit -keypass changeit -verbose -verify Signed.jar]
      [2021-04-30T05:26:04.162379235Z] Gathering output for process 22978
      [2021-04-30T05:26:04.952680896Z] Waiting for completion for process 22978
      [2021-04-30T05:26:04.953242327Z] Waiting for completion finished for process 22978
      Output and diagnostic info for process 22978 was saved into 'pid-22978-output.log'

      s k 496 Fri Apr 30 05:26:04 UTC 2021 META-INF/MANIFEST.MF
              637 Fri Apr 30 05:26:04 UTC 2021 META-INF/JAVATEST.SF
             1414 Fri Apr 30 05:26:04 UTC 2021 META-INF/JAVATEST.DSA
                0 Fri Apr 30 05:26:02 UTC 2021 META-INF/
                0 Fri Apr 30 05:26:00 UTC 2021 META-INF/versions/9/
                0 Fri Apr 30 05:26:00 UTC 2021 META-INF/versions/9/version/
                0 Fri Apr 30 05:26:00 UTC 2021 META-INF/versions/10/
                0 Fri Apr 30 05:26:00 UTC 2021 META-INF/versions/10/version/
                0 Fri Apr 30 05:26:00 UTC 2021 version/
      smk 2142 Fri Apr 30 05:26:00 UTC 2021 META-INF/versions/9/version/Version.class
      smk 256 Fri Apr 30 05:26:00 UTC 2021 META-INF/versions/10/version/Version.class
      smk 932 Fri Apr 30 05:26:00 UTC 2021 version/Main.class
      smk 256 Fri Apr 30 05:26:00 UTC 2021 version/Version.class

        s = signature was verified
        m = entry is listed in manifest
        k = at least one certificate was found in keystore

      - Signed by "CN=sample"
          Digest algorithm: SHA-256
          Signature algorithm: SHA256withDSA, 2048-bit key

      jar verified.

      [2021-04-30T05:26:04.954736810Z] Waiting for completion for process 22978
      [2021-04-30T05:26:04.954979548Z] Waiting for completion finished for process 22978
      Command line: [/opt/mach5/mesos/work_dir/jib-master/install/jdk-17+20-1757/linux-x64.jdk/jdk-17/bin/jarsigner -J-Duser.language=en -J-Duser.country=US -keystore keystore.jks -storepass changeit -keypass changeit -verbose -verify Signed.jar]
      [2021-04-30T05:26:05.317203031Z] Gathering output for process 23069
      [2021-04-30T05:26:06.344964115Z] Waiting for completion for process 23069
      [2021-04-30T05:26:06.345142291Z] Waiting for completion finished for process 23069
      Output and diagnostic info for process 23069 was saved into 'pid-23069-output.log'

      s k 496 Fri Apr 30 05:26:04 UTC 2021 META-INF/MANIFEST.MF
              637 Fri Apr 30 05:26:04 UTC 2021 META-INF/SIGNER.SF
             1413 Fri Apr 30 05:26:04 UTC 2021 META-INF/SIGNER.DSA
                0 Fri Apr 30 05:26:02 UTC 2021 META-INF/
                0 Fri Apr 30 05:26:00 UTC 2021 META-INF/versions/9/
                0 Fri Apr 30 05:26:00 UTC 2021 META-INF/versions/9/version/
                0 Fri Apr 30 05:26:00 UTC 2021 META-INF/versions/10/
                0 Fri Apr 30 05:26:00 UTC 2021 META-INF/versions/10/version/
                0 Fri Apr 30 05:26:00 UTC 2021 version/
      smk 2142 Fri Apr 30 05:26:00 UTC 2021 META-INF/versions/9/version/Version.class
      smk 256 Fri Apr 30 05:26:00 UTC 2021 META-INF/versions/10/version/Version.class
      smk 932 Fri Apr 30 05:26:00 UTC 2021 version/Main.class
      smk 256 Fri Apr 30 05:26:00 UTC 2021 version/Version.class

        s = signature was verified
        m = entry is listed in manifest
        k = at least one certificate was found in keystore

      - Signed by "CN=sample"
          Digest algorithm: SHA-256
          Signature algorithm: SHA256withDSA, 2048-bit key

      jar verified.

      [2021-04-30T05:26:06.346691377Z] Waiting for completion for process 23069
      [2021-04-30T05:26:06.347206110Z] Waiting for completion finished for process 23069
      Command line: [/opt/mach5/mesos/work_dir/jib-master/install/jdk-17+20-1757/linux-x64.jdk/jdk-17/bin/java -cp /opt/mach5/mesos/work_dir/slaves/28992c6d-c35d-42fa-8099-898223bf183b-S25558/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/21d96a7c-b855-4627-9565-3309128684c4/runs/5638a90c-9b4d-49c8-82a0-294fa0979a69/testoutput/test-support/jtreg_open_test_jdk_tier2_part1/classes/1/sun/security/tools/jarsigner/multiRelease/MVJarSigningTest.d:/opt/mach5/mesos/work_dir/jib-master/install/jdk-17+20-1757/src.full/open/test/jdk/sun/security/tools/jarsigner/multiRelease:/opt/mach5/mesos/work_dir/slaves/28992c6d-c35d-42fa-8099-898223bf183b-S25558/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/21d96a7c-b855-4627-9565-3309128684c4/runs/5638a90c-9b4d-49c8-82a0-294fa0979a69/testoutput/test-support/jtreg_open_test_jdk_tier2_part1/classes/1/test/lib:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/javatest.jar:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/jtreg.jar:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/junit.jar:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/testng.jar -Xmx512m -XX:MaxRAMPercentage=6 -Djava.io.tmpdir=/opt/mach5/mesos/work_dir/slaves/28992c6d-c35d-42fa-8099-898223bf183b-S25558/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/21d96a7c-b855-4627-9565-3309128684c4/runs/5638a90c-9b4d-49c8-82a0-294fa0979a69/testoutput/test-support/jtreg_open_test_jdk_tier2_part1/tmp -ea -esa -classpath Signed.jar -Djava.security.manager -Djava.security.policy=/opt/mach5/mesos/work_dir/jib-master/install/jdk-17+20-1757/src.full/open/test/jdk/sun/security/tools/jarsigner/multiRelease/SignedJar.policy version.Main ]
      [2021-04-30T05:26:06.362092532Z] Gathering output for process 23301
      [2021-04-30T05:40:37.331211970Z] Waiting for completion for process 23301
      [2021-04-30T05:40:37.331424832Z] Waiting for completion finished for process 23301
      Output and diagnostic info for process 23301 was saved into 'pid-23301-output.log'
      [2021-04-30T05:40:37.332123953Z] Waiting for completion for process 23301
      [2021-04-30T05:40:37.332207431Z] Waiting for completion finished for process 23301
      [2021-04-30T05:40:37.332280429Z] Waiting for completion for process 23301
      [2021-04-30T05:40:37.332393422Z] Waiting for completion finished for process 23301
      ----------System.err:(20/1111)----------
      Note: /opt/mach5/mesos/work_dir/jib-master/install/jdk-17+20-1757/src.full/open/test/jdk/sun/security/tools/jarsigner/multiRelease/jarContent/v9/version/Version.java uses unchecked or unsafe operations.
      Note: Recompile with -Xlint:unchecked for details.
       stdout: [];
       stderr: []
       exitValue = 134

      java.lang.RuntimeException: Expected to get exit value of [0]

      at jdk.test.lib.process.OutputAnalyzer.shouldHaveExitValue(OutputAnalyzer.java:474)
      at MVJarSigningTest.main(MVJarSigningTest.java:124)
      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:568)
      at com.sun.javatest.regtest.agent.MainActionHelper$AgentVMRunnable.run(MainActionHelper.java:298)
      at java.base/java.lang.Thread.run(Thread.java:831)

      JavaTest Message: Test threw exception: java.lang.RuntimeException
      JavaTest Message: shutting down test

      result: Error. Agent error: java.lang.Exception: Agent 29 timed out with a timeout of 480 seconds; check console log for any additional details

      I'm starting this as a P3 since this is Tier2 failure.

            fguallini Fernando Guallini
            dcubed Daniel Daugherty
            Votes:
            0 Vote for this issue
            Watchers:
            2 Start watching this issue

              Created:
              Updated:
              Resolved: