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

java/net/httpclient/http2/TLSConnection.java fails on Windows 8.1

    Details

    • Subcomponent:
    • CPU:
      x86_64
    • OS:
      windows_8

      Description

      This failure can be reproduced on two Windows 8.1. X64 environment. It is strange that not reproducible on Win 7 and 2012 ( Repeated 10+ times each).
      It is some kind of intermittent, but quite frequent on Windows 8.1(tried 10 times, only twice passed).


      This is full test output:
      ----------System.out:(53/3527)----------
      Test #1: SSL parameters is null, expect NPE
      Got an exception:
      java.lang.NullPointerException
      at java.base/java.util.Objects.requireNonNull(Objects.java:221)
      at jdk.incubator.httpclient/jdk.incubator.http.HttpClientBuilderImpl.sslParameters(HttpClientBuilderImpl.java:69)
      at jdk.incubator.httpclient/jdk.incubator.http.HttpClientBuilderImpl.sslParameters(HttpClientBuilderImpl.java:37)
      at TLSConnection.connect(TLSConnection.java:164)
      at TLSConnection.lambda$main$0(TLSConnection.java:81)
      at TLSConnection.expectFailure(TLSConnection.java:229)
      at TLSConnection.main(TLSConnection.java:79)
      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:563)
      at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:115)
      at java.base/java.lang.Thread.run(Thread.java:844)
      Passed: expected exception
      Test #2: default SSL parameters, expect successful connection
      ServerSettings: SETTINGS: length=30, streamid=0, flags=0 Settings: HEADER_TABLE_SIZE=4096 ENABLE_PUSH=1 MAX_CONCURRENT_STREAMS=35 INITIAL_WINDOW_SIZE=65535 MAX_FRAME_SIZE=16384
      ClientSettings: SETTINGS: length=30, streamid=0, flags=0 Settings: HEADER_TABLE_SIZE=16384 ENABLE_PUSH=1 MAX_CONCURRENT_STREAMS=16 INITIAL_WINDOW_SIZE=65535 MAX_FRAME_SIZE=16384
      Handler: received request to https://127.0.0.1:59946/
      Handler: read 4 bytes of body:
      body
      Response: Test response
      Passed
      Check protocol: negotiated protocol: TLSv1.2
      Check protocol: expected protocol: TLSv1.2
      Test #3: SSL parameters with SSL_DHE_RSA_WITH_3DES_EDE_CBC_SHA cipher suite, expect successful connection
      ServerSettings: SETTINGS: length=30, streamid=0, flags=0 Settings: HEADER_TABLE_SIZE=4096 ENABLE_PUSH=1 MAX_CONCURRENT_STREAMS=35 INITIAL_WINDOW_SIZE=65535 MAX_FRAME_SIZE=16384
      ClientSettings: SETTINGS: length=30, streamid=0, flags=0 Settings: HEADER_TABLE_SIZE=16384 ENABLE_PUSH=1 MAX_CONCURRENT_STREAMS=16 INITIAL_WINDOW_SIZE=65535 MAX_FRAME_SIZE=16384
      Handler: received request to https://127.0.0.1:59946/
      Handler: read 4 bytes of body:
      body
      Response: Test response
      Passed
      Check protocol: negotiated protocol: TLSv1.2
      Check protocol: expected protocol: TLSv1.2
      Check protocol: negotiated ciphersuite: SSL_DHE_RSA_WITH_3DES_EDE_CBC_SHA
      Check protocol: expected ciphersuite: SSL_DHE_RSA_WITH_3DES_EDE_CBC_SHA
      Test #4: SSL parameters with TLS_RSA_WITH_AES_128_CBC_SHA cipher suite, expect successful connection
      ServerSettings: SETTINGS: length=30, streamid=0, flags=0 Settings: HEADER_TABLE_SIZE=4096 ENABLE_PUSH=1 MAX_CONCURRENT_STREAMS=35 INITIAL_WINDOW_SIZE=65535 MAX_FRAME_SIZE=16384
      ClientSettings: SETTINGS: length=30, streamid=0, flags=0 Settings: HEADER_TABLE_SIZE=16384 ENABLE_PUSH=1 MAX_CONCURRENT_STREAMS=16 INITIAL_WINDOW_SIZE=65535 MAX_FRAME_SIZE=16384
      Handler: received request to https://127.0.0.1:59946/
      Handler: read 4 bytes of body:
      body
      Response: Test response
      Passed
      Check protocol: negotiated protocol: TLSv1.2
      Check protocol: expected protocol: TLSv1.1
      Check protocol: unexpected negotiated protocol
      Check protocol: negotiated ciphersuite: SSL_DHE_RSA_WITH_3DES_EDE_CBC_SHA
      Check protocol: expected ciphersuite: TLS_RSA_WITH_AES_128_CBC_SHA
      Check protocol: unexpected negotiated ciphersuite
      ----------System.err:(410/27010)----------
      WARNING: Using incubator modules: jdk.incubator.httpclient
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.MultiExchange requestFilters
      INFO: TRACE: Applying request filters
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.MultiExchange requestFilters
      INFO: TRACE: Applying jdk.incubator.http.AuthenticationFilter@4d05650
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.MultiExchange requestFilters
      INFO: TRACE: Applying jdk.incubator.http.RedirectFilter@6d4b7b0c
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.MultiExchange requestFilters
      INFO: TRACE: All filters applied
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.AsyncSSLDelegate logParams
      INFO: SSL: SSLParameters:
          application protocol: h2

      Apr 06, 2017 12:43:02 PM jdk.incubator.http.Http2Connection <init>
      INFO: TRACE: Connection send window size 65,535
      TestServer: New connection from 7afd5d92[SSL_NULL_WITH_NULL_NULL: Socket[addr=/127.0.0.1,port=59949,localport=59946]]
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.Http2Connection sendConnectionPreface
      INFO: TRACE: /127.0.0.1:59949: start sending connection preface to /127.0.0.1:59946
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.Http2Connection sendConnectionPreface
      INFO: FRAME: OUT: SETTINGS: length=30, streamid=0, flags=0 Settings: HEADER_TABLE_SIZE=16384 ENABLE_PUSH=1 MAX_CONCURRENT_STREAMS=16 INITIAL_WINDOW_SIZE=65535 MAX_FRAME_SIZE=16384
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.AsyncSSLDelegate upperWrite
      INFO: TRACE: Write: needs handshake
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.AsyncSSLDelegate doHandshakeNow
      INFO: TRACE: Write: Starting handshake...
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.AsyncSSLDelegate doHandshakeNow
      INFO: TRACE: Write: Handshake completed
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.Http2Connection sendConnectionPreface
      INFO: TRACE: PREFACE_BYTES sent
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.Http2Connection sendConnectionPreface
      INFO: TRACE: Settings Frame sent
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.Http2Connection$PrefaceController waitUntilPrefaceSent
      INFO: TRACE: Waiting until connection preface is sent
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.Http2Connection encodeFrame
      INFO: FRAME: OUT: WINDOW_UPDATE: length=4, streamid=0, flags=0 WindowUpdate: 196609
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.Http2Connection sendConnectionPreface
      INFO: TRACE: finished sending connection preface
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.Http2Connection$PrefaceController waitUntilPrefaceSent
      INFO: TRACE: Preface sent: resuming reading
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.Http2Connection processFrame
      INFO: FRAME: IN: SETTINGS: length=30, streamid=0, flags=0 Settings: HEADER_TABLE_SIZE=4096 ENABLE_PUSH=1 MAX_CONCURRENT_STREAMS=35 INITIAL_WINDOW_SIZE=65535 MAX_FRAME_SIZE=16384
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.Http2Connection encodeFrame
      INFO: FRAME: OUT: SETTINGS: length=0, streamid=0, flags=ACK Settings:
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.Stream sendHeadersOnly
      INFO: REQUEST: https://127.0.0.1:59946 POST
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.Http2Connection encodeHeaders
      INFO: HEADERS: HEADERS FRAME (stream=1)
          :authority: 127.0.0.1:59946
          :method: POST
          :path: /
          :scheme: https
          content-length: 4

      Apr 06, 2017 12:43:02 PM jdk.incubator.http.Http2Connection lambda$encodeFrames$1
      INFO: FRAME: OUT: HEADERS: length=21, streamid=1, flags=END_HEADERS
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.Http2Connection encodeFrame
      INFO: FRAME: OUT: DATA: length=4, streamid=1, flags=END_STREAM
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.Stream getResponse
      INFO: TRACE: Waiting for response (streamid=1)
      Apr 06, 2017 12:43:02 PM jdk.incubator.http.Stream getResponseAsync
      INFO: TRACE: Response future (stream=1) is: jdk.incubator.http.internal.common.MinimalFuture@52ec5aa1[Not completed] (id=3)
      TestServer: POST /
      Server: creating input stream for stream 1
      Using handler for: /
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection processFrame
      INFO: FRAME: IN: WINDOW_UPDATE: length=4, streamid=1, flags=0 WindowUpdate: 4
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection processFrame
      INFO: FRAME: IN: WINDOW_UPDATE: length=4, streamid=0, flags=0 WindowUpdate: 4
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection processFrame
      INFO: FRAME: IN: HEADERS: length=7, streamid=1, flags=END_HEADERS
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream lambda$new$3
      INFO: TRACE: RECEIVED HEADER (streamid=1): :status: 200
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream lambda$new$3
      INFO: TRACE: RECEIVED HEADER (streamid=1): content-length: 13
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream incoming
      INFO: TRACE: handling response (streamid=1)
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream handleResponse
      INFO: HEADERS: RESPONSE HEADERS:
          :status: 200
          content-length: 13

      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream completeResponse
      INFO: TRACE: Completing response (streamid=1): jdk.incubator.http.internal.common.MinimalFuture@52ec5aa1[Not completed, 1 dependents] (id=3)
      Sent response headers 200
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream getResponse
      INFO: TRACE: Got response or failed (streamid=1)
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.HttpResponseImpl logResponse
      INFO: RESPONSE: (POST https://127.0.0.1:59946) 200
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.MultiExchange responseFilters
      INFO: TRACE: Applying response filters
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.MultiExchange responseFilters
      INFO: TRACE: Applying jdk.incubator.http.AuthenticationFilter@4d05650
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.MultiExchange responseFilters
      INFO: TRACE: Applying jdk.incubator.http.RedirectFilter@6d4b7b0c
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.MultiExchange responseFilters
      INFO: TRACE: All filters applied
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream readBodyAsync
      INFO: TRACE: Reading body on stream 1
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection processFrame
      INFO: FRAME: IN: DATA: length=13, streamid=1, flags=0
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection processFrame
      INFO: FRAME: IN: DATA: length=0, streamid=1, flags=END_STREAM
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream close
      INFO: TRACE: Closing stream 1
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream close
      INFO: TRACE: Stream 1 closed
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.MultiExchange requestFilters
      INFO: TRACE: Applying request filters
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.MultiExchange requestFilters
      INFO: TRACE: Applying jdk.incubator.http.AuthenticationFilter@594b75e5
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.MultiExchange requestFilters
      INFO: TRACE: Applying jdk.incubator.http.RedirectFilter@589e68b0
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.MultiExchange requestFilters
      INFO: TRACE: All filters applied
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.AsyncSSLDelegate logParams
      INFO: SSL: SSLParameters:
          cipher: SSL_DHE_RSA_WITH_3DES_EDE_CBC_SHA
          application protocol: h2

      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection <init>
      INFO: TRACE: Connection send window size 65,535
      TestServer: New connection from 4d892e36[SSL_NULL_WITH_NULL_NULL: Socket[addr=/127.0.0.1,port=59952,localport=59946]]
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection sendConnectionPreface
      INFO: TRACE: /127.0.0.1:59952: start sending connection preface to /127.0.0.1:59946
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection sendConnectionPreface
      INFO: FRAME: OUT: SETTINGS: length=30, streamid=0, flags=0 Settings: HEADER_TABLE_SIZE=16384 ENABLE_PUSH=1 MAX_CONCURRENT_STREAMS=16 INITIAL_WINDOW_SIZE=65535 MAX_FRAME_SIZE=16384
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.AsyncSSLDelegate upperWrite
      INFO: TRACE: Write: needs handshake
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.AsyncSSLDelegate doHandshakeNow
      INFO: TRACE: Write: Starting handshake...
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.AsyncSSLDelegate doHandshakeNow
      INFO: TRACE: Write: Handshake completed
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection sendConnectionPreface
      INFO: TRACE: PREFACE_BYTES sent
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection sendConnectionPreface
      INFO: TRACE: Settings Frame sent
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection encodeFrame
      INFO: FRAME: OUT: WINDOW_UPDATE: length=4, streamid=0, flags=0 WindowUpdate: 196609
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection sendConnectionPreface
      INFO: TRACE: finished sending connection preface
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream sendHeadersOnly
      INFO: REQUEST: https://127.0.0.1:59946 POST
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection encodeHeaders
      INFO: HEADERS: HEADERS FRAME (stream=1)
          :authority: 127.0.0.1:59946
          :method: POST
          :path: /
          :scheme: https
          content-length: 4

      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection lambda$encodeFrames$1
      INFO: FRAME: OUT: HEADERS: length=21, streamid=1, flags=END_HEADERS
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection encodeFrame
      INFO: FRAME: OUT: DATA: length=4, streamid=1, flags=END_STREAM
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream getResponse
      INFO: TRACE: Waiting for response (streamid=1)
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream getResponseAsync
      INFO: TRACE: Response future (stream=1) is: jdk.incubator.http.internal.common.MinimalFuture@1e0c5797[Not completed] (id=7)
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection processFrame
      INFO: FRAME: IN: SETTINGS: length=30, streamid=0, flags=0 Settings: HEADER_TABLE_SIZE=4096 ENABLE_PUSH=1 MAX_CONCURRENT_STREAMS=35 INITIAL_WINDOW_SIZE=65535 MAX_FRAME_SIZE=16384
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection encodeFrame
      INFO: FRAME: OUT: SETTINGS: length=0, streamid=0, flags=ACK Settings:
      TestServer: POST /
      Server: creating input stream for stream 1
      Using handler for: /
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection processFrame
      INFO: FRAME: IN: WINDOW_UPDATE: length=4, streamid=1, flags=0 WindowUpdate: 4
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection processFrame
      INFO: FRAME: IN: WINDOW_UPDATE: length=4, streamid=0, flags=0 WindowUpdate: 4
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection processFrame
      INFO: FRAME: IN: HEADERS: length=7, streamid=1, flags=END_HEADERS
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream lambda$new$3
      INFO: TRACE: RECEIVED HEADER (streamid=1): :status: 200
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream lambda$new$3
      INFO: TRACE: RECEIVED HEADER (streamid=1): content-length: 13
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream incoming
      INFO: TRACE: handling response (streamid=1)
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream handleResponse
      INFO: HEADERS: RESPONSE HEADERS:
          :status: 200
          content-length: 13

      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream completeResponse
      INFO: TRACE: Completing response (streamid=1): jdk.incubator.http.internal.common.MinimalFuture@1e0c5797[Not completed, 1 dependents] (id=7)
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream getResponse
      INFO: TRACE: Got response or failed (streamid=1)
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.HttpResponseImpl logResponse
      INFO: RESPONSE: (POST https://127.0.0.1:59946) 200
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.MultiExchange responseFilters
      INFO: TRACE: Applying response filters
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.MultiExchange responseFilters
      INFO: TRACE: Applying jdk.incubator.http.AuthenticationFilter@594b75e5
      Sent response headers 200
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection processFrame
      INFO: FRAME: IN: DATA: length=13, streamid=1, flags=0
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.MultiExchange responseFilters
      INFO: TRACE: Applying jdk.incubator.http.RedirectFilter@589e68b0
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.MultiExchange responseFilters
      INFO: TRACE: All filters applied
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream readBodyAsync
      INFO: TRACE: Reading body on stream 1
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection processFrame
      INFO: FRAME: IN: DATA: length=0, streamid=1, flags=END_STREAM
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream close
      INFO: TRACE: Closing stream 1
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream close
      INFO: TRACE: Stream 1 closed
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.MultiExchange requestFilters
      INFO: TRACE: Applying request filters
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.MultiExchange requestFilters
      INFO: TRACE: Applying jdk.incubator.http.AuthenticationFilter@6c8257c9
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.MultiExchange requestFilters
      INFO: TRACE: Applying jdk.incubator.http.RedirectFilter@50923f61
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.MultiExchange requestFilters
      INFO: TRACE: All filters applied
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.AsyncSSLDelegate logParams
      INFO: SSL: SSLParameters:
          cipher: TLS_RSA_WITH_AES_128_CBC_SHA
          application protocol: h2
          protocol: TLSv1.1

      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection <init>
      INFO: TRACE: Connection send window size 65,535
      TestServer: New connection from 153f4f1[SSL_NULL_WITH_NULL_NULL: Socket[addr=/127.0.0.1,port=59955,localport=59946]]
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection sendConnectionPreface
      INFO: TRACE: /127.0.0.1:59955: start sending connection preface to /127.0.0.1:59946
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection sendConnectionPreface
      INFO: FRAME: OUT: SETTINGS: length=30, streamid=0, flags=0 Settings: HEADER_TABLE_SIZE=16384 ENABLE_PUSH=1 MAX_CONCURRENT_STREAMS=16 INITIAL_WINDOW_SIZE=65535 MAX_FRAME_SIZE=16384
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.AsyncSSLDelegate upperWrite
      INFO: TRACE: Write: needs handshake
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.AsyncSSLDelegate doHandshakeNow
      INFO: TRACE: Write: Starting handshake...
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.AsyncSSLDelegate doHandshakeNow
      INFO: TRACE: Write: Handshake completed
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection sendConnectionPreface
      INFO: TRACE: PREFACE_BYTES sent
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection sendConnectionPreface
      INFO: TRACE: Settings Frame sent
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection encodeFrame
      INFO: FRAME: OUT: WINDOW_UPDATE: length=4, streamid=0, flags=0 WindowUpdate: 196609
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection sendConnectionPreface
      INFO: TRACE: finished sending connection preface
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream sendHeadersOnly
      INFO: REQUEST: https://127.0.0.1:59946 POST
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection encodeHeaders
      INFO: HEADERS: HEADERS FRAME (stream=1)
          :authority: 127.0.0.1:59946
          :method: POST
          :path: /
          :scheme: https
          content-length: 4

      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection lambda$encodeFrames$1
      INFO: FRAME: OUT: HEADERS: length=21, streamid=1, flags=END_HEADERS
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection encodeFrame
      INFO: FRAME: OUT: DATA: length=4, streamid=1, flags=END_STREAM
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream getResponse
      INFO: TRACE: Waiting for response (streamid=1)
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream getResponseAsync
      INFO: TRACE: Response future (stream=1) is: jdk.incubator.http.internal.common.MinimalFuture@35e65f51[Not completed] (id=11)
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection processFrame
      INFO: FRAME: IN: SETTINGS: length=30, streamid=0, flags=0 Settings: HEADER_TABLE_SIZE=4096 ENABLE_PUSH=1 MAX_CONCURRENT_STREAMS=35 INITIAL_WINDOW_SIZE=65535 MAX_FRAME_SIZE=16384
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection encodeFrame
      INFO: FRAME: OUT: SETTINGS: length=0, streamid=0, flags=ACK Settings:
      TestServer: POST /
      Server: creating input stream for stream 1
      Using handler for: /
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection processFrame
      INFO: FRAME: IN: WINDOW_UPDATE: length=4, streamid=1, flags=0 WindowUpdate: 4
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection processFrame
      INFO: FRAME: IN: WINDOW_UPDATE: length=4, streamid=0, flags=0 WindowUpdate: 4
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection processFrame
      INFO: FRAME: IN: HEADERS: length=7, streamid=1, flags=END_HEADERS
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream lambda$new$3
      INFO: TRACE: RECEIVED HEADER (streamid=1): :status: 200
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream lambda$new$3
      INFO: TRACE: RECEIVED HEADER (streamid=1): content-length: 13
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream incoming
      INFO: TRACE: handling response (streamid=1)
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream handleResponse
      INFO: HEADERS: RESPONSE HEADERS:
          :status: 200
          content-length: 13

      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream completeResponse
      INFO: TRACE: Completing response (streamid=1): jdk.incubator.http.internal.common.MinimalFuture@35e65f51[Not completed, 1 dependents] (id=11)
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream getResponse
      INFO: TRACE: Got response or failed (streamid=1)
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.HttpResponseImpl logResponse
      INFO: RESPONSE: (POST https://127.0.0.1:59946) 200
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.MultiExchange responseFilters
      INFO: TRACE: Applying response filters
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.MultiExchange responseFilters
      INFO: TRACE: Applying jdk.incubator.http.AuthenticationFilter@6c8257c9
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.MultiExchange responseFilters
      INFO: TRACE: Applying jdk.incubator.http.RedirectFilter@50923f61
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.MultiExchange responseFilters
      INFO: TRACE: All filters applied
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream readBodyAsync
      INFO: TRACE: Reading body on stream 1
      Sent response headers 200
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection processFrame
      INFO: FRAME: IN: DATA: length=13, streamid=1, flags=0
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection processFrame
      INFO: FRAME: IN: DATA: length=0, streamid=1, flags=END_STREAM
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream close
      INFO: TRACE: Closing stream 1
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Stream close
      INFO: TRACE: Stream 1 closed
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.PlainHttpConnection close
      INFO: ERROR: Closing: PlainHttpConnection: HttpConnection: java.nio.channels.SocketChannel[connected local=/127.0.0.1:59952 remote=/127.0.0.1:59946]
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection shutdown
      INFO: ERROR: java.io.IOException: Engine closed
      at jdk.incubator.httpclient/jdk.incubator.http.AsyncSSLDelegate.unwrapBuffer(AsyncSSLDelegate.java:456)
      at jdk.incubator.httpclient/jdk.incubator.http.AsyncSSLDelegate.upperRead(AsyncSSLDelegate.java:499)
      at jdk.incubator.httpclient/jdk.incubator.http.internal.common.Queue.put(Queue.java:93)
      at jdk.incubator.httpclient/jdk.incubator.http.AsyncSSLDelegate.asyncReceive(AsyncSSLDelegate.java:474)
      at jdk.incubator.httpclient/jdk.incubator.http.PlainHttpConnection.asyncRead(PlainHttpConnection.java:283)
      at jdk.incubator.httpclient/jdk.incubator.http.PlainHttpConnection$ReadEvent.handle(PlainHttpConnection.java:379)
      at jdk.incubator.httpclient/jdk.incubator.http.HttpClientImpl$SelectorManager.handleEvent(HttpClientImpl.java:428)
      at jdk.incubator.httpclient/jdk.incubator.http.HttpClientImpl$SelectorManager.run(HttpClientImpl.java:398)

      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection shutdown
      INFO: ERROR: java.io.IOException: Connection aborted
      at jdk.incubator.httpclient/jdk.incubator.http.PlainHttpConnection.shutdown(PlainHttpConnection.java:265)
      at jdk.incubator.httpclient/jdk.incubator.http.PlainHttpConnection.asyncRead(PlainHttpConnection.java:286)
      at jdk.incubator.httpclient/jdk.incubator.http.PlainHttpConnection$ReadEvent.handle(PlainHttpConnection.java:379)
      at jdk.incubator.httpclient/jdk.incubator.http.HttpClientImpl$SelectorManager.handleEvent(HttpClientImpl.java:428)
      at jdk.incubator.httpclient/jdk.incubator.http.HttpClientImpl$SelectorManager.run(HttpClientImpl.java:398)

      Apr 06, 2017 12:43:03 PM jdk.incubator.http.PlainHttpConnection close
      INFO: ERROR: Closing: PlainHttpConnection: HttpConnection: java.nio.channels.SocketChannel[connected local=/127.0.0.1:59955 remote=/127.0.0.1:59946]
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection shutdown
      INFO: ERROR: java.io.IOException: Engine closed
      at jdk.incubator.httpclient/jdk.incubator.http.AsyncSSLDelegate.unwrapBuffer(AsyncSSLDelegate.java:456)
      at jdk.incubator.httpclient/jdk.incubator.http.AsyncSSLDelegate.upperRead(AsyncSSLDelegate.java:499)
      at jdk.incubator.httpclient/jdk.incubator.http.internal.common.Queue.put(Queue.java:93)
      at jdk.incubator.httpclient/jdk.incubator.http.AsyncSSLDelegate.asyncReceive(AsyncSSLDelegate.java:474)
      at jdk.incubator.httpclient/jdk.incubator.http.PlainHttpConnection.asyncRead(PlainHttpConnection.java:283)
      at jdk.incubator.httpclient/jdk.incubator.http.PlainHttpConnection$ReadEvent.handle(PlainHttpConnection.java:379)
      at jdk.incubator.httpclient/jdk.incubator.http.HttpClientImpl$SelectorManager.handleEvent(HttpClientImpl.java:428)
      at jdk.incubator.httpclient/jdk.incubator.http.HttpClientImpl$SelectorManager.run(HttpClientImpl.java:398)

      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection shutdown
      INFO: ERROR: java.io.IOException: Connection aborted
      at jdk.incubator.httpclient/jdk.incubator.http.PlainHttpConnection.shutdown(PlainHttpConnection.java:265)
      at jdk.incubator.httpclient/jdk.incubator.http.PlainHttpConnection.asyncRead(PlainHttpConnection.java:286)
      at jdk.incubator.httpclient/jdk.incubator.http.PlainHttpConnection$ReadEvent.handle(PlainHttpConnection.java:379)
      at jdk.incubator.httpclient/jdk.incubator.http.HttpClientImpl$SelectorManager.handleEvent(HttpClientImpl.java:428)
      at jdk.incubator.httpclient/jdk.incubator.http.HttpClientImpl$SelectorManager.run(HttpClientImpl.java:398)

      Apr 06, 2017 12:43:03 PM jdk.incubator.http.PlainHttpConnection close
      INFO: ERROR: Closing: PlainHttpConnection: HttpConnection: java.nio.channels.SocketChannel[connected local=/127.0.0.1:59949 remote=/127.0.0.1:59946]
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection shutdown
      INFO: ERROR: java.io.IOException: Engine closed
      at jdk.incubator.httpclient/jdk.incubator.http.AsyncSSLDelegate.unwrapBuffer(AsyncSSLDelegate.java:456)
      at jdk.incubator.httpclient/jdk.incubator.http.AsyncSSLDelegate.upperRead(AsyncSSLDelegate.java:499)
      at jdk.incubator.httpclient/jdk.incubator.http.internal.common.Queue.put(Queue.java:93)
      at jdk.incubator.httpclient/jdk.incubator.http.AsyncSSLDelegate.asyncReceive(AsyncSSLDelegate.java:474)
      at jdk.incubator.httpclient/jdk.incubator.http.PlainHttpConnection.asyncRead(PlainHttpConnection.java:283)
      at jdk.incubator.httpclient/jdk.incubator.http.PlainHttpConnection$ReadEvent.handle(PlainHttpConnection.java:379)
      at jdk.incubator.httpclient/jdk.incubator.http.HttpClientImpl$SelectorManager.handleEvent(HttpClientImpl.java:428)
      at jdk.incubator.httpclient/jdk.incubator.http.HttpClientImpl$SelectorManager.run(HttpClientImpl.java:398)

      java.lang.RuntimeException: At least one test case failed
      at TLSConnection.main(TLSConnection.java:119)
      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:563)
      at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:115)
      at java.base/java.lang.Thread.run(Thread.java:844)
      Apr 06, 2017 12:43:03 PM jdk.incubator.http.Http2Connection shutdown
      INFO: ERROR: java.io.IOException: Connection aborted
      at jdk.incubator.httpclient/jdk.incubator.http.PlainHttpConnection.shutdown(PlainHttpConnection.java:265)
      at jdk.incubator.httpclient/jdk.incubator.http.PlainHttpConnection.asyncRead(PlainHttpConnection.java:286)
      at jdk.incubator.httpclient/jdk.incubator.http.PlainHttpConnection$ReadEvent.handle(PlainHttpConnection.java:379)
      at jdk.incubator.httpclient/jdk.incubator.http.HttpClientImpl$SelectorManager.handleEvent(HttpClientImpl.java:428)
      at jdk.incubator.httpclient/jdk.incubator.http.HttpClientImpl$SelectorManager.run(HttpClientImpl.java:398)


      JavaTest Message: Test threw exception: java.lang.RuntimeException: At least one test case failed
      JavaTest Message: shutting down test

      java.io.IOException: java.lang.InterruptedException
      at jdk.incubator.httpclient/jdk.incubator.http.internal.common.Queue.take(Queue.java:147)
      at Http2TestServerConnection.writeLoop(Http2TestServerConnection.java:520)
      at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514)
      at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
      at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1161)
      at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
      at java.base/java.lang.Thread.run(Thread.java:844)
      Caused by: java.lang.InterruptedException
      at java.base/java.lang.Object.wait(Native Method)
      at java.base/java.lang.Object.wait(Object.java:516)
      at jdk.incubator.httpclient/jdk.incubator.http.internal.common.Queue.take(Queue.java:139)
      ... 6 more
      java.io.IOException: java.lang.InterruptedException
      at jdk.incubator.httpclient/jdk.incubator.http.internal.common.Queue.take(Queue.java:147)
      at Http2TestServerConnection.writeLoop(Http2TestServerConnection.java:520)
      at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514)
      at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
      at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1161)
      at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
      at java.base/java.lang.Thread.run(Thread.java:844)
      Caused by: java.lang.InterruptedException
      at java.base/java.lang.Object.wait(Native Method)
      at java.base/java.lang.Object.wait(Object.java:516)
      at jdk.incubator.httpclient/jdk.incubator.http.internal.common.Queue.take(Queue.java:139)
      ... 6 more

        Attachments

          Activity

            People

            • Assignee:
              michaelm Michael McMahon
              Reporter:
              xiaofeya Xiaofeng Yang
            • Votes:
              0 Vote for this issue
              Watchers:
              2 Start watching this issue

              Dates

              • Created:
                Updated:
                Resolved: