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

sun/security/ssl/Stapling/StatusResponseManager.java failed due to "SocketTimeoutException: Read timed out"

    Details

      Description

      The following test failed in the JDK14 CI:

      sun/security/ssl/Stapling/StatusResponseManager.java

      All four subtests failed in this instance. Here's a snippet from the log file:

      #section:main
      ----------messages:(5/662)----------
      command: main -Djavax.net.debug=ssl:respmgr java.base/sun.security.ssl.StatusResponseManagerTests
      reason: User specified action: run main/othervm -Djavax.net.debug=ssl:respmgr java.base/sun.security.ssl.StatusResponseManagerTests
      Mode: othervm [/othervm specified, test or library uses bootclasspath, test or library overrides a system module]
      Additional options from @modules: --add-modules java.base --add-exports java.base/sun.security.provider.certpath=ALL-UNNAMED --add-exports java.base/sun.security.util=ALL-UNNAMED --add-exports java.base/sun.security.x509=ALL-UNNAMED --add-exports java.base/sun.security.ssl=ALL-UNNAMED
      elapsed time (seconds): 29.634
      ----------configuration:(9/669)----------
      Boot Layer
        add modules: java.base
        add exports: java.base/sun.security.provider.certpath ALL-UNNAMED
                     java.base/sun.security.ssl ALL-UNNAMED
                     java.base/sun.security.util ALL-UNNAMED
                     java.base/sun.security.x509 ALL-UNNAMED
        add reads: java.base ALL-UNNAMED
        patch: java.base /export/home/opt/mach5/mesos/work_dir/74d876d4-c236-495e-ad49-db1cc1f98cc1/testoutput/test-support/jtreg_open_test_jdk_tier2/classes/10/sun/security/ssl/Stapling/StatusResponseManager.d/patches/java.base

      ----------System.out:(34/1185)----------
      Generated Root CA KeyPair
      Generated Intermediate CA KeyPair
      Generated SSL Cert KeyPair
      Root CA Created:
      Issuer: CN=Root CA Cert, O=SomeCompany
      Subject: CN=Root CA Cert, O=SomeCompany
      Serial: 1

      Root OCSP Responder URI is http://localhost:32937
      Intermediate CA Created:
      Issuer: CN=Root CA Cert, O=SomeCompany
      Subject: CN=Intermediate CA Cert, O=SomeCompany
      Serial: 100

      Intermediate CA OCSP Responder URI is http://localhost:42563
      SSL Certificate Created:
      Issuer: CN=Intermediate CA Cert, O=SomeCompany
      Subject: CN=SSLCertificate, O=SomeCompany
      Serial: 4096

      ============ Tests ============
      Test 1: Basic OCSP fetch test
      Result: FAIL Incorrect number of responses: expected 1, got 0
      -------------------------------------------
      Test 2: Clear StatusResponseManager cache
      Result: FAIL Incorrect number of responses: expected 2, got 1
      -------------------------------------------
      Test 3: Basic OCSP_MULTI fetch test
      Result: FAIL Incorrect number of responses: expected 2, got 1
      -------------------------------------------
      Test 4: Test Cache Expiration
      Result: FAIL Incorrect number of responses: expected 2, got 1
      -------------------------------------------
      End Results: 0 Passed, 4 Failed.
      ----------System.err:(103/9734)----------
      javax.net.ssl|DEBUG|1C|MainThread|2020-01-16 09:59:54.897 UTC|StatusResponseManager.java:272|Beginning check: Type = OCSP, Chain length = 3
      javax.net.ssl|DEBUG|1C|MainThread|2020-01-16 09:59:54.905 UTC|StatusResponseManager.java:422|Check cache for SN4096: MISS
      javax.net.ssl|DEBUG|1F|pool-3-thread-1|2020-01-16 09:59:54.909 UTC|StatusResponseManager.java:616|Starting fetch for SN 4096
      javax.net.ssl|DEBUG|1F|pool-3-thread-1|2020-01-16 09:59:54.910 UTC|StatusResponseManager.java:634|Attempting fetch from http://localhost:42563
      javax.net.ssl|DEBUG|1C|MainThread|2020-01-16 09:59:59.910 UTC|StatusResponseManager.java:366|Found cancelled task
      javax.net.ssl|DEBUG|1C|MainThread|2020-01-16 09:59:59.913 UTC|StatusResponseManager.java:272|Beginning check: Type = OCSP_MULTI, Chain length = 3
      javax.net.ssl|DEBUG|1C|MainThread|2020-01-16 09:59:59.914 UTC|StatusResponseManager.java:422|Check cache for SN4096: MISS
      javax.net.ssl|DEBUG|1C|MainThread|2020-01-16 09:59:59.915 UTC|StatusResponseManager.java:422|Check cache for SN100: MISS
      javax.net.ssl|DEBUG|21|pool-5-thread-1|2020-01-16 09:59:59.919 UTC|StatusResponseManager.java:616|Starting fetch for SN 100
      javax.net.ssl|DEBUG|20|pool-4-thread-1|2020-01-16 09:59:59.919 UTC|StatusResponseManager.java:616|Starting fetch for SN 4096
      javax.net.ssl|DEBUG|21|pool-5-thread-1|2020-01-16 09:59:59.920 UTC|StatusResponseManager.java:634|Attempting fetch from http://localhost:32937
      javax.net.ssl|DEBUG|20|pool-4-thread-1|2020-01-16 09:59:59.920 UTC|StatusResponseManager.java:634|Attempting fetch from http://localhost:42563
      javax.net.ssl|DEBUG|21|pool-5-thread-1|2020-01-16 09:59:59.957 UTC|StatusResponseManager.java:665|OCSP Status: SUCCESSFUL (1321 bytes)
      javax.net.ssl|DEBUG|21|pool-5-thread-1|2020-01-16 09:59:59.958 UTC|StatusResponseManager.java:708|Added response for SN 100 to cache
      javax.net.ssl|DEBUG|1C|MainThread|2020-01-16 10:00:04.916 UTC|StatusResponseManager.java:366|Found cancelled task
      javax.net.ssl|DEBUG|1C|MainThread|2020-01-16 10:00:04.918 UTC|StatusResponseManager.java:272|Beginning check: Type = OCSP_MULTI, Chain length = 3
      javax.net.ssl|DEBUG|1C|MainThread|2020-01-16 10:00:04.919 UTC|StatusResponseManager.java:422|Check cache for SN4096: MISS
      javax.net.ssl|DEBUG|1C|MainThread|2020-01-16 10:00:04.920 UTC|StatusResponseManager.java:422|Check cache for SN100: MISS
      javax.net.ssl|DEBUG|23|pool-7-thread-1|2020-01-16 10:00:04.921 UTC|StatusResponseManager.java:616|Starting fetch for SN 4096
      javax.net.ssl|DEBUG|24|pool-8-thread-1|2020-01-16 10:00:04.922 UTC|StatusResponseManager.java:616|Starting fetch for SN 100
      javax.net.ssl|DEBUG|23|pool-7-thread-1|2020-01-16 10:00:04.922 UTC|StatusResponseManager.java:634|Attempting fetch from http://localhost:42563
      javax.net.ssl|DEBUG|24|pool-8-thread-1|2020-01-16 10:00:04.922 UTC|StatusResponseManager.java:634|Attempting fetch from http://localhost:32937
      javax.net.ssl|DEBUG|24|pool-8-thread-1|2020-01-16 10:00:04.940 UTC|StatusResponseManager.java:665|OCSP Status: SUCCESSFUL (1321 bytes)
      javax.net.ssl|DEBUG|24|pool-8-thread-1|2020-01-16 10:00:04.941 UTC|StatusResponseManager.java:708|Added response for SN 100 to cache
      javax.net.ssl|DEBUG|1C|MainThread|2020-01-16 10:00:09.921 UTC|StatusResponseManager.java:366|Found cancelled task
      javax.net.ssl|DEBUG|1C|MainThread|2020-01-16 10:00:09.923 UTC|StatusResponseManager.java:272|Beginning check: Type = OCSP_MULTI, Chain length = 3
      javax.net.ssl|DEBUG|1C|MainThread|2020-01-16 10:00:09.924 UTC|StatusResponseManager.java:422|Check cache for SN4096: MISS
      javax.net.ssl|DEBUG|1C|MainThread|2020-01-16 10:00:09.924 UTC|StatusResponseManager.java:422|Check cache for SN100: MISS
      javax.net.ssl|DEBUG|26|pool-10-thread-1|2020-01-16 10:00:09.925 UTC|StatusResponseManager.java:616|Starting fetch for SN 4096
      javax.net.ssl|DEBUG|26|pool-10-thread-1|2020-01-16 10:00:09.925 UTC|StatusResponseManager.java:634|Attempting fetch from http://localhost:42563
      javax.net.ssl|DEBUG|27|pool-11-thread-1|2020-01-16 10:00:09.925 UTC|StatusResponseManager.java:616|Starting fetch for SN 100
      javax.net.ssl|DEBUG|27|pool-11-thread-1|2020-01-16 10:00:09.926 UTC|StatusResponseManager.java:634|Attempting fetch from http://localhost:32937
      javax.net.ssl|DEBUG|1F|pool-3-thread-1|2020-01-16 10:00:09.931 UTC|StatusResponseManager.java:684|Caught exception: (
      "throwable" : {
        java.net.SocketTimeoutException: Read timed out
         at java.base/sun.nio.ch.NioSocketImpl.timedRead(NioSocketImpl.java:283)
         at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:309)
         at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:350)
         at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:803)
         at java.base/java.net.Socket$SocketInputStream.read(Socket.java:982)
         at java.base/java.io.BufferedInputStream.fill(BufferedInputStream.java:244)
         at java.base/java.io.BufferedInputStream.read1(BufferedInputStream.java:284)
         at java.base/java.io.BufferedInputStream.read(BufferedInputStream.java:343)
         at java.base/sun.net.www.http.HttpClient.parseHTTPHeader(HttpClient.java:754)
         at java.base/sun.net.www.http.HttpClient.parseHTTP(HttpClient.java:689)
         at java.base/sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1618)
         at java.base/sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1523)
         at java.base/sun.security.provider.certpath.OCSP.getOCSPBytes(OCSP.java:254)
         at java.base/sun.security.ssl.StatusResponseManager$OCSPFetchCall.call(StatusResponseManager.java:654)
         at java.base/sun.security.ssl.StatusResponseManager$OCSPFetchCall.call(StatusResponseManager.java:580)
         at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
         at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
         at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
         at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304)
         at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
         at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:630)
         at java.base/java.lang.Thread.run(Thread.java:832)}

      )
      javax.net.ssl|DEBUG|27|pool-11-thread-1|2020-01-16 10:00:09.940 UTC|StatusResponseManager.java:665|OCSP Status: SUCCESSFUL (1321 bytes)
      javax.net.ssl|DEBUG|27|pool-11-thread-1|2020-01-16 10:00:09.941 UTC|StatusResponseManager.java:708|Added response for SN 100 to cache
      javax.net.ssl|DEBUG|1C|MainThread|2020-01-16 10:00:14.925 UTC|StatusResponseManager.java:366|Found cancelled task
      javax.net.ssl|DEBUG|20|pool-4-thread-1|2020-01-16 10:00:14.925 UTC|StatusResponseManager.java:684|Caught exception: (
      "throwable" : {
        java.net.SocketTimeoutException: Read timed out
         at java.base/sun.nio.ch.NioSocketImpl.timedRead(NioSocketImpl.java:283)
         at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:309)
         at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:350)
         at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:803)
         at java.base/java.net.Socket$SocketInputStream.read(Socket.java:982)
         at java.base/java.io.BufferedInputStream.fill(BufferedInputStream.java:244)
         at java.base/java.io.BufferedInputStream.read1(BufferedInputStream.java:284)
         at java.base/java.io.BufferedInputStream.read(BufferedInputStream.java:343)
         at java.base/sun.net.www.http.HttpClient.parseHTTPHeader(HttpClient.java:754)
         at java.base/sun.net.www.http.HttpClient.parseHTTP(HttpClient.java:689)
         at java.base/sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1618)
         at java.base/sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1523)
         at java.base/sun.security.provider.certpath.OCSP.getOCSPBytes(OCSP.java:254)
         at java.base/sun.security.ssl.StatusResponseManager$OCSPFetchCall.call(StatusResponseManager.java:654)
         at java.base/sun.security.ssl.StatusResponseManager$OCSPFetchCall.call(StatusResponseManager.java:580)
         at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
         at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
         at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
         at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304)
         at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
         at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:630)
         at java.base/java.lang.Thread.run(Thread.java:832)}

      )
      java.lang.RuntimeException: One or more tests failed, see test output for details
      at java.base/sun.security.ssl.StatusResponseManagerTests.runTests(StatusResponseManagerTests.java:484)
      at java.base/sun.security.ssl.StatusResponseManagerTests.main(StatusResponseManagerTests.java:92)
      at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
      at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      at java.base/java.lang.reflect.Method.invoke(Method.java:564)
      at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:127)
      at java.base/java.lang.Thread.run(Thread.java:832)

      JavaTest Message: Test threw exception: java.lang.RuntimeException: One or more tests failed, see test output for details
      JavaTest Message: shutting down test

      STATUS:Failed.`main' threw exception: java.lang.RuntimeException: One or more tests failed, see test output for details
      ----------rerun:(46/5455)*----------

        Attachments

          Activity

            People

            • Assignee:
              jnimeh Jamil Nimeh
              Reporter:
              dcubed Daniel Daugherty
            • Votes:
              0 Vote for this issue
              Watchers:
              5 Start watching this issue

              Dates

              • Created:
                Updated: