search

dotnet / aspnetcore

ASP.NET Core is a cross-platform .NET framework for building modern cloud-based web applications on Windows, Mac, or Linux.
https://asp.net
MIT License
35.59k stars 10.06k forks source link

Test failure: Http3 GET_GracefulServerShutdown_RequestCompleteSuccessfullyInsideHostTimeout #37913

Closed HaoK closed 3 years ago

HaoK commented 3 years ago

Failing Test(s)

Interop.FunctionalTests.Http3.Http3RequestTests.GET_GracefulServerShutdown_RequestCompleteSuccessfullyInsideHostTimeout

Error Message

System.Net.Http.HttpRequestException: Error while copying content to a stream.
 |  ---> System.IO.IOException: The request was aborted.
 |  ---> System.IO.IOException: The decryption operation failed, see inner exception.
 |  ---> System.ComponentModel.Win32Exception (0x80090330): The specified data could not be decrypted.

Stacktrace

```text | [0.356s] Interop.FunctionalTests.Http3.Http3RequestTests Error: Test threw an exception. | System.Net.Http.HttpRequestException: Error while copying content to a stream. | ---> System.IO.IOException: The request was aborted. | ---> System.IO.IOException: The decryption operation failed, see inner exception. | ---> System.ComponentModel.Win32Exception (0x80090330): The specified data could not be decrypted. | --- End of inner exception stack trace --- | at System.Net.Security.SslStream.ReadAsyncInternal[TIOAdapter](TIOAdapter adapter, Memory`1 buffer) | at System.Net.Http.Http2Connection.ProcessIncomingFramesAsync() | --- End of inner exception stack trace --- | at System.Net.Http.Http2Connection.ThrowRequestAborted(Exception innerException) | at System.Net.Http.Http2Connection.Http2Stream.CheckResponseBodyState() | at System.Net.Http.Http2Connection.Http2Stream.TryReadFromBuffer(Span`1 buffer, Boolean partOfSyncRead) | at System.Net.Http.Http2Connection.Http2Stream.CopyToAsync(HttpResponseMessage responseMessage, Stream destination, Int32 bufferSize, CancellationToken cancellationToken) | at System.Net.Http.HttpConnectionResponseContent.SerializeToStreamAsync(Stream stream, TransportContext context, CancellationToken cancellationToken) | at System.Net.Http.HttpContent.LoadIntoBufferAsyncCore(Task serializeToStreamTask, MemoryStream tempBuffer) | --- End of inner exception stack trace --- | at System.Net.Http.HttpContent.LoadIntoBufferAsyncCore(Task serializeToStreamTask, MemoryStream tempBuffer) | at System.Net.Http.HttpContent.WaitAndReturnAsync[TState,TResult](Task waitTask, TState state, Func`2 returnFunc) | at Interop.FunctionalTests.Http3.Http3RequestTests.GET_GracefulServerShutdown_RequestCompleteSuccessfullyInsideHostTimeout(HttpProtocols protocol) in /_/src/Servers/Kestrel/test/Interop.FunctionalTests/Http3/Http3RequestTests.cs:line 1630 ```

Logs

https://helixre8s23ayyeko0k025g8.blob.core.windows.net/dotnet-aspnetcore-refs-heads-main-9b90c7d5544c475fb2/Interop.FunctionalTests--net7.0/1/console.f6874ec9.log?sv=2019-07-07&se=2021-11-16T22%3A13%3A20Z&sr=c&sp=rl&sig=f45%2FHrlq5n7SHHJdkOzcLz70upCs%2BWCp2OBYwQCRI7I%3D ```text [xUnit.net 00:00:09.40] Interop.FunctionalTests.Http3.Http3RequestTests.GET_GracefulServerShutdown_RequestCompleteSuccessfullyInsideHostTimeout(protocol: Http2) [FAIL] [xUnit.net 00:00:09.40] System.Net.Http.HttpRequestException : Error while copying content to a stream. [xUnit.net 00:00:09.40] ---- System.IO.IOException : The request was aborted. [xUnit.net 00:00:09.40] -------- System.IO.IOException : The decryption operation failed, see inner exception. [xUnit.net 00:00:09.40] ------------ System.ComponentModel.Win32Exception : The specified data could not be decrypted. [xUnit.net 00:00:09.40] Stack Trace: [xUnit.net 00:00:09.41] at System.Net.Http.HttpContent.LoadIntoBufferAsyncCore(Task serializeToStreamTask, MemoryStream tempBuffer) [xUnit.net 00:00:09.41] at System.Net.Http.HttpContent.WaitAndReturnAsync[TState,TResult](Task waitTask, TState state, Func`2 returnFunc) [xUnit.net 00:00:09.41] /_/src/Servers/Kestrel/test/Interop.FunctionalTests/Http3/Http3RequestTests.cs(1630,0): at Interop.FunctionalTests.Http3.Http3RequestTests.GET_GracefulServerShutdown_RequestCompleteSuccessfullyInsideHostTimeout(HttpProtocols protocol) [xUnit.net 00:00:09.41] --- End of stack trace from previous location --- [xUnit.net 00:00:09.41] ----- Inner Stack Trace ----- [xUnit.net 00:00:09.41] at System.Net.Http.Http2Connection.ThrowRequestAborted(Exception innerException) [xUnit.net 00:00:09.41] at System.Net.Http.Http2Connection.Http2Stream.CheckResponseBodyState() [xUnit.net 00:00:09.41] at System.Net.Http.Http2Connection.Http2Stream.TryReadFromBuffer(Span`1 buffer, Boolean partOfSyncRead) [xUnit.net 00:00:09.41] at System.Net.Http.Http2Connection.Http2Stream.CopyToAsync(HttpResponseMessage responseMessage, Stream destination, Int32 bufferSize, CancellationToken cancellationToken) [xUnit.net 00:00:09.41] at System.Net.Http.HttpConnectionResponseContent.SerializeToStreamAsync(Stream stream, TransportContext context, CancellationToken cancellationToken) [xUnit.net 00:00:09.41] at System.Net.Http.HttpContent.LoadIntoBufferAsyncCore(Task serializeToStreamTask, MemoryStream tempBuffer) [xUnit.net 00:00:09.41] ----- Inner Stack Trace ----- [xUnit.net 00:00:09.41] at System.Net.Security.SslStream.ReadAsyncInternal[TIOAdapter](TIOAdapter adapter, Memory`1 buffer) [xUnit.net 00:00:09.41] at System.Net.Http.Http2Connection.ProcessIncomingFramesAsync() [xUnit.net 00:00:09.41] ----- Inner Stack Trace ----- [xUnit.net 00:00:09.41] [xUnit.net 00:00:09.41] Output: [xUnit.net 00:00:09.41] | [0.001s] TestLifetime Information: Starting test GET_GracefulServerShutdown_RequestCompleteSuccessfullyInsideHostTimeout-Http2 at 2021-10-27T22:19:18 [xUnit.net 00:00:09.41] | [0.005s] Microsoft.Extensions.Hosting.Internal.Host Debug: Hosting starting [xUnit.net 00:00:09.41] | [0.008s] Microsoft.AspNetCore.Server.Kestrel.Core.KestrelServer Debug: Using development certificate: CN=localhost (Thumbprint: 22CFE68B87851D71D2970792A7F766EF4B7CE809) [xUnit.net 00:00:09.41] | [0.050s] Microsoft.Hosting.Lifetime Information: Now listening on: https://127.0.0.1:54328 [xUnit.net 00:00:09.41] | [0.050s] Microsoft.AspNetCore.Hosting.Diagnostics Debug: Loaded hosting startup assembly Interop.FunctionalTests [xUnit.net 00:00:09.41] | [0.050s] Microsoft.Hosting.Lifetime Information: Application started. Press Ctrl+C to shut down. [xUnit.net 00:00:09.41] | [0.050s] Microsoft.Hosting.Lifetime Information: Hosting environment: Production [xUnit.net 00:00:09.41] | [0.050s] Microsoft.Hosting.Lifetime Information: Content root path: C:\h\w\B01509B1\w\99150836\e\ [xUnit.net 00:00:09.41] | [0.050s] Microsoft.Extensions.Hosting.Internal.Host Debug: Hosting started [xUnit.net 00:00:09.41] | [0.066s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMCPIV7R6GCJ" accepted. [xUnit.net 00:00:09.41] | [0.066s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMCPIV7R6GCJ" started. [xUnit.net 00:00:09.41] | [0.081s] Microsoft.AspNetCore.Server.Kestrel.Https.Internal.HttpsConnectionMiddleware Debug: Connection 0HMCPIV7R6GCJ established using the following protocol: Tls13 [xUnit.net 00:00:09.41] | [0.081s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" sending SETTINGS frame for stream ID 0 with length 18 and flags NONE. [xUnit.net 00:00:09.41] | [0.081s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" sending WINDOW_UPDATE frame for stream ID 0 with length 4 and flags 0x0. [xUnit.net 00:00:09.41] | [0.081s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" received SETTINGS frame for stream ID 0 with length 12 and flags NONE. [xUnit.net 00:00:09.41] | [0.082s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" sending SETTINGS frame for stream ID 0 with length 0 and flags ACK. [xUnit.net 00:00:09.41] | [0.082s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" received WINDOW_UPDATE frame for stream ID 0 with length 4 and flags 0x0. [xUnit.net 00:00:09.41] | [0.083s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" received HEADERS frame for stream ID 1 with length 20 and flags END_HEADERS. [xUnit.net 00:00:09.41] | [0.083s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" received SETTINGS frame for stream ID 0 with length 0 and flags ACK. [xUnit.net 00:00:09.41] | [0.083s] Microsoft.AspNetCore.Hosting.Diagnostics Information: Request starting HTTP/2 POST https://127.0.0.1:54328/ - - [xUnit.net 00:00:09.41] | [0.085s] Interop.FunctionalTests.Http3.Http3RequestTests Information: Server sending response headers [xUnit.net 00:00:09.41] | [0.085s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" sending HEADERS frame for stream ID 1 with length 41 and flags END_HEADERS. [xUnit.net 00:00:09.41] | [0.085s] Interop.FunctionalTests.Http3.Http3RequestTests Information: Server reading [xUnit.net 00:00:09.41] | [0.093s] Microsoft.AspNetCore.Server.Kestrel Debug: Connection id "0HMCPIV7R6GCJ", Request id "0HMCPIV7R6GCJ:00000001": started reading request body. [xUnit.net 00:00:09.41] | [0.097s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" received PING frame for stream ID 0 with length 8 and flags NONE. [xUnit.net 00:00:09.41] | [0.097s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" sending PING frame for stream ID 0 with length 8 and flags ACK. [xUnit.net 00:00:09.41] | [0.214s] Interop.FunctionalTests.Http3.Http3RequestTests Information: Stopping host [xUnit.net 00:00:09.41] | [0.215s] Microsoft.Extensions.Hosting.Internal.Host Debug: Hosting stopping [xUnit.net 00:00:09.41] | [0.215s] Microsoft.Hosting.Lifetime Information: Application is shutting down... [xUnit.net 00:00:09.41] | [0.215s] Interop.FunctionalTests.Http3.Http3RequestTests Information: Client completing request stream [xUnit.net 00:00:09.41] | [0.216s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" received DATA frame for stream ID 1 with length 11 and flags NONE. [xUnit.net 00:00:09.41] | [0.216s] Microsoft.AspNetCore.Server.Kestrel.Http2 Debug: Connection id "0HMCPIV7R6GCJ" is closing. [xUnit.net 00:00:09.41] | [0.217s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" sending GOAWAY frame for stream ID 0 with length 8 and flags 0x0. [xUnit.net 00:00:09.41] | [0.222s] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets Debug: Connection id "0HMCPIV7R6GCJ" received FIN. [xUnit.net 00:00:09.41] | [0.222s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" received DATA frame for stream ID 1 with length 0 and flags END_STREAM. [xUnit.net 00:00:09.41] | [0.223s] Microsoft.AspNetCore.Server.Kestrel.Http2 Debug: Connection id "0HMCPIV7R6GCJ" is closed. The last processed stream ID was 1. [xUnit.net 00:00:09.41] | [0.223s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" sending GOAWAY frame for stream ID 0 with length 8 and flags 0x0. [xUnit.net 00:00:09.41] | [0.223s] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets Debug: Connection id "0HMCPIV7R6GCJ" sending FIN because: "The client closed the connection." [xUnit.net 00:00:09.41] | [0.224s] Microsoft.AspNetCore.Server.Kestrel Debug: Connection id "0HMCPIV7R6GCJ", Request id "0HMCPIV7R6GCJ:00000001": done reading request body. [xUnit.net 00:00:09.41] | [0.226s] Microsoft.AspNetCore.Server.Kestrel Error: Connection id "0HMCPIV7R6GCJ", Request id "0HMCPIV7R6GCJ:00000001": An unhandled exception was thrown by the application. [xUnit.net 00:00:09.41] | System.IO.IOException: The request stream was aborted. [xUnit.net 00:00:09.41] | ---> Microsoft.AspNetCore.Connections.ConnectionAbortedException: The HTTP/2 connection faulted. [xUnit.net 00:00:09.41] | --- End of inner exception stack trace --- [xUnit.net 00:00:09.41] | at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.HttpRequestStream.ReadAsyncInternal(Memory`1 destination, CancellationToken cancellationToken) in /_/src/Servers/Kestrel/Core/src/Internal/Http/HttpRequestStream.cs:line 149 [xUnit.net 00:00:09.41] | at System.IO.StreamExtensions.ReadUntilEndAsync(Stream stream, Int32 bufferLength, CancellationToken cancellationToken) in /_/src/Servers/Kestrel/shared/test/StreamExtensions.cs:line 84 [xUnit.net 00:00:09.41] | at Interop.FunctionalTests.Http3.Http3RequestTests.<>c__DisplayClass28_0.<b__0>d.MoveNext() in /_/src/Servers/Kestrel/test/Interop.FunctionalTests/Http3/Http3RequestTests.cs:line 1589 [xUnit.net 00:00:09.41] | --- End of stack trace from previous location --- [xUnit.net 00:00:09.41] | at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.HttpProtocol.ProcessRequests[TContext](IHttpApplication`1 application) in /_/src/Servers/Kestrel/Core/src/Internal/Http/HttpProtocol.cs:line 667 [xUnit.net 00:00:09.41] | [0.227s] Microsoft.AspNetCore.Hosting.Diagnostics Information: Request finished HTTP/2 POST https://127.0.0.1:54328/ - - - 200 - - 143.4931ms [xUnit.net 00:00:09.41] | [0.227s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMCPIV7R6GCJ" stopped. [xUnit.net 00:00:09.41] | [0.228s] Microsoft.Extensions.Hosting.Internal.Host Debug: Hosting stopped [xUnit.net 00:00:09.41] | [0.356s] Interop.FunctionalTests.Http3.Http3RequestTests Error: Test threw an exception. [xUnit.net 00:00:09.41] | System.Net.Http.HttpRequestException: Error while copying content to a stream. [xUnit.net 00:00:09.41] | ---> System.IO.IOException: The request was aborted. [xUnit.net 00:00:09.41] | ---> System.IO.IOException: The decryption operation failed, see inner exception. [xUnit.net 00:00:09.42] | ---> System.ComponentModel.Win32Exception (0x80090330): The specified data could not be decrypted. [xUnit.net 00:00:09.42] | --- End of inner exception stack trace --- [xUnit.net 00:00:09.42] | at System.Net.Security.SslStream.ReadAsyncInternal[TIOAdapter](TIOAdapter adapter, Memory`1 buffer) [xUnit.net 00:00:09.42] | at System.Net.Http.Http2Connection.ProcessIncomingFramesAsync() [xUnit.net 00:00:09.42] | --- End of inner exception stack trace --- [xUnit.net 00:00:09.42] | at System.Net.Http.Http2Connection.ThrowRequestAborted(Exception innerException) [xUnit.net 00:00:09.42] | at System.Net.Http.Http2Connection.Http2Stream.CheckResponseBodyState() [xUnit.net 00:00:09.42] | at System.Net.Http.Http2Connection.Http2Stream.TryReadFromBuffer(Span`1 buffer, Boolean partOfSyncRead) [xUnit.net 00:00:09.42] | at System.Net.Http.Http2Connection.Http2Stream.CopyToAsync(HttpResponseMessage responseMessage, Stream destination, Int32 bufferSize, CancellationToken cancellationToken) [xUnit.net 00:00:09.42] | at System.Net.Http.HttpConnectionResponseContent.SerializeToStreamAsync(Stream stream, TransportContext context, CancellationToken cancellationToken) [xUnit.net 00:00:09.42] | at System.Net.Http.HttpContent.LoadIntoBufferAsyncCore(Task serializeToStreamTask, MemoryStream tempBuffer) [xUnit.net 00:00:09.42] | --- End of inner exception stack trace --- [xUnit.net 00:00:09.42] | at System.Net.Http.HttpContent.LoadIntoBufferAsyncCore(Task serializeToStreamTask, MemoryStream tempBuffer) [xUnit.net 00:00:09.42] | at System.Net.Http.HttpContent.WaitAndReturnAsync[TState,TResult](Task waitTask, TState state, Func`2 returnFunc) [xUnit.net 00:00:09.42] | at Interop.FunctionalTests.Http3.Http3RequestTests.GET_GracefulServerShutdown_RequestCompleteSuccessfullyInsideHostTimeout(HttpProtocols protocol) in /_/src/Servers/Kestrel/test/Interop.FunctionalTests/Http3/Http3RequestTests.cs:line 1630 [xUnit.net 00:00:09.42] | at Xunit.Sdk.TestInvoker`1.<>c__DisplayClass48_1.<b__1>d.MoveNext() in C:\Dev\xunit\xunit\src\xunit.execution\Sdk\Frameworks\Runners\TestInvoker.cs:line 273 [xUnit.net 00:00:09.42] | --- End of stack trace from previous location --- [xUnit.net 00:00:09.42] | at Xunit.Sdk.ExecutionTimer.AggregateAsync(Func`1 asyncAction) in C:\Dev\xunit\xunit\src\xunit.execution\Sdk\Frameworks\ExecutionTimer.cs:line 54 [xUnit.net 00:00:09.42] | at Xunit.Sdk.ExceptionAggregator.RunAsync(Func`1 code) in C:\Dev\xunit\xunit\src\xunit.core\Sdk\ExceptionAggregator.cs:line 96 [xUnit.net 00:00:09.42] | [0.366s] TestLifetime Information: Finished test GET_GracefulServerShutdown_RequestCompleteSuccessfullyInsideHostTimeout-Http2 in 0.364772s Failed Interop.FunctionalTests.Http3.Http3RequestTests.GET_GracefulServerShutdown_RequestCompleteSuccessfullyInsideHostTimeout(protocol: Http2) [330 ms] Error Message: System.Net.Http.HttpRequestException : Error while copying content to a stream. ---- System.IO.IOException : The request was aborted. -------- System.IO.IOException : The decryption operation failed, see inner exception. ------------ System.ComponentModel.Win32Exception : The specified data could not be decrypted. Stack Trace: at System.Net.Http.HttpContent.LoadIntoBufferAsyncCore(Task serializeToStreamTask, MemoryStream tempBuffer) at System.Net.Http.HttpContent.WaitAndReturnAsync[TState,TResult](Task waitTask, TState state, Func`2 returnFunc) at Interop.FunctionalTests.Http3.Http3RequestTests.GET_GracefulServerShutdown_RequestCompleteSuccessfullyInsideHostTimeout(HttpProtocols protocol) in /_/src/Servers/Kestrel/test/Interop.FunctionalTests/Http3/Http3RequestTests.cs:line 1630 --- End of stack trace from previous location --- ----- Inner Stack Trace ----- at System.Net.Http.Http2Connection.ThrowRequestAborted(Exception innerException) at System.Net.Http.Http2Connection.Http2Stream.CheckResponseBodyState() at System.Net.Http.Http2Connection.Http2Stream.TryReadFromBuffer(Span`1 buffer, Boolean partOfSyncRead) at System.Net.Http.Http2Connection.Http2Stream.CopyToAsync(HttpResponseMessage responseMessage, Stream destination, Int32 bufferSize, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionResponseContent.SerializeToStreamAsync(Stream stream, TransportContext context, CancellationToken cancellationToken) at System.Net.Http.HttpContent.LoadIntoBufferAsyncCore(Task serializeToStreamTask, MemoryStream tempBuffer) ----- Inner Stack Trace ----- at System.Net.Security.SslStream.ReadAsyncInternal[TIOAdapter](TIOAdapter adapter, Memory`1 buffer) at System.Net.Http.Http2Connection.ProcessIncomingFramesAsync() ----- Inner Stack Trace ----- Standard Output Messages: | [0.001s] TestLifetime Information: Starting test GET_GracefulServerShutdown_RequestCompleteSuccessfullyInsideHostTimeout-Http2 at 2021-10-27T22:19:18 | [0.005s] Microsoft.Extensions.Hosting.Internal.Host Debug: Hosting starting | [0.008s] Microsoft.AspNetCore.Server.Kestrel.Core.KestrelServer Debug: Using development certificate: CN=localhost (Thumbprint: 22CFE68B87851D71D2970792A7F766EF4B7CE809) | [0.050s] Microsoft.Hosting.Lifetime Information: Now listening on: https://127.0.0.1:54328 | [0.050s] Microsoft.AspNetCore.Hosting.Diagnostics Debug: Loaded hosting startup assembly Interop.FunctionalTests | [0.050s] Microsoft.Hosting.Lifetime Information: Application started. Press Ctrl+C to shut down. | [0.050s] Microsoft.Hosting.Lifetime Information: Hosting environment: Production | [0.050s] Microsoft.Hosting.Lifetime Information: Content root path: C:\h\w\B01509B1\w\99150836\e\ | [0.050s] Microsoft.Extensions.Hosting.Internal.Host Debug: Hosting started | [0.066s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMCPIV7R6GCJ" accepted. | [0.066s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMCPIV7R6GCJ" started. | [0.081s] Microsoft.AspNetCore.Server.Kestrel.Https.Internal.HttpsConnectionMiddleware Debug: Connection 0HMCPIV7R6GCJ established using the following protocol: Tls13 | [0.081s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" sending SETTINGS frame for stream ID 0 with length 18 and flags NONE. | [0.081s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" sending WINDOW_UPDATE frame for stream ID 0 with length 4 and flags 0x0. | [0.081s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" received SETTINGS frame for stream ID 0 with length 12 and flags NONE. | [0.082s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" sending SETTINGS frame for stream ID 0 with length 0 and flags ACK. | [0.082s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" received WINDOW_UPDATE frame for stream ID 0 with length 4 and flags 0x0. | [0.083s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" received HEADERS frame for stream ID 1 with length 20 and flags END_HEADERS. | [0.083s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" received SETTINGS frame for stream ID 0 with length 0 and flags ACK. | [0.083s] Microsoft.AspNetCore.Hosting.Diagnostics Information: Request starting HTTP/2 POST https://127.0.0.1:54328/ - - | [0.085s] Interop.FunctionalTests.Http3.Http3RequestTests Information: Server sending response headers | [0.085s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" sending HEADERS frame for stream ID 1 with length 41 and flags END_HEADERS. | [0.085s] Interop.FunctionalTests.Http3.Http3RequestTests Information: Server reading | [0.093s] Microsoft.AspNetCore.Server.Kestrel Debug: Connection id "0HMCPIV7R6GCJ", Request id "0HMCPIV7R6GCJ:00000001": started reading request body. | [0.097s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" received PING frame for stream ID 0 with length 8 and flags NONE. | [0.097s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" sending PING frame for stream ID 0 with length 8 and flags ACK. | [0.214s] Interop.FunctionalTests.Http3.Http3RequestTests Information: Stopping host | [0.215s] Microsoft.Extensions.Hosting.Internal.Host Debug: Hosting stopping | [0.215s] Microsoft.Hosting.Lifetime Information: Application is shutting down... | [0.215s] Interop.FunctionalTests.Http3.Http3RequestTests Information: Client completing request stream | [0.216s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" received DATA frame for stream ID 1 with length 11 and flags NONE. | [0.216s] Microsoft.AspNetCore.Server.Kestrel.Http2 Debug: Connection id "0HMCPIV7R6GCJ" is closing. | [0.217s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" sending GOAWAY frame for stream ID 0 with length 8 and flags 0x0. | [0.222s] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets Debug: Connection id "0HMCPIV7R6GCJ" received FIN. | [0.222s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" received DATA frame for stream ID 1 with length 0 and flags END_STREAM. | [0.223s] Microsoft.AspNetCore.Server.Kestrel.Http2 Debug: Connection id "0HMCPIV7R6GCJ" is closed. The last processed stream ID was 1. | [0.223s] Microsoft.AspNetCore.Server.Kestrel.Http2 Trace: Connection id "0HMCPIV7R6GCJ" sending GOAWAY frame for stream ID 0 with length 8 and flags 0x0. | [0.223s] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets Debug: Connection id "0HMCPIV7R6GCJ" sending FIN because: "The client closed the connection." | [0.224s] Microsoft.AspNetCore.Server.Kestrel Debug: Connection id "0HMCPIV7R6GCJ", Request id "0HMCPIV7R6GCJ:00000001": done reading request body. | [0.226s] Microsoft.AspNetCore.Server.Kestrel Error: Connection id "0HMCPIV7R6GCJ", Request id "0HMCPIV7R6GCJ:00000001": An unhandled exception was thrown by the application. | System.IO.IOException: The request stream was aborted. | ---> Microsoft.AspNetCore.Connections.ConnectionAbortedException: The HTTP/2 connection faulted. | --- End of inner exception stack trace --- | at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.HttpRequestStream.ReadAsyncInternal(Memory`1 destination, CancellationToken cancellationToken) in /_/src/Servers/Kestrel/Core/src/Internal/Http/HttpRequestStream.cs:line 149 | at System.IO.StreamExtensions.ReadUntilEndAsync(Stream stream, Int32 bufferLength, CancellationToken cancellationToken) in /_/src/Servers/Kestrel/shared/test/StreamExtensions.cs:line 84 | at Interop.FunctionalTests.Http3.Http3RequestTests.<>c__DisplayClass28_0.<b__0>d.MoveNext() in /_/src/Servers/Kestrel/test/Interop.FunctionalTests/Http3/Http3RequestTests.cs:line 1589 | --- End of stack trace from previous location --- | at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.HttpProtocol.ProcessRequests[TContext](IHttpApplication`1 application) in /_/src/Servers/Kestrel/Core/src/Internal/Http/HttpProtocol.cs:line 667 | [0.227s] Microsoft.AspNetCore.Hosting.Diagnostics Information: Request finished HTTP/2 POST https://127.0.0.1:54328/ - - - 200 - - 143.4931ms | [0.227s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMCPIV7R6GCJ" stopped. | [0.228s] Microsoft.Extensions.Hosting.Internal.Host Debug: Hosting stopped | [0.356s] Interop.FunctionalTests.Http3.Http3RequestTests Error: Test threw an exception. | System.Net.Http.HttpRequestException: Error while copying content to a stream. | ---> System.IO.IOException: The request was aborted. | ---> System.IO.IOException: The decryption operation failed, see inner exception. | ---> System.ComponentModel.Win32Exception (0x80090330): The specified data could not be decrypted. | --- End of inner exception stack trace --- | at System.Net.Security.SslStream.ReadAsyncInternal[TIOAdapter](TIOAdapter adapter, Memory`1 buffer) | at System.Net.Http.Http2Connection.ProcessIncomingFramesAsync() | --- End of inner exception stack trace --- | at System.Net.Http.Http2Connection.ThrowRequestAborted(Exception innerException) | at System.Net.Http.Http2Connection.Http2Stream.CheckResponseBodyState() | at System.Net.Http.Http2Connection.Http2Stream.TryReadFromBuffer(Span`1 buffer, Boolean partOfSyncRead) | at System.Net.Http.Http2Connection.Http2Stream.CopyToAsync(HttpResponseMessage responseMessage, Stream destination, Int32 bufferSize, CancellationToken cancellationToken) | at System.Net.Http.HttpConnectionResponseContent.SerializeToStreamAsync(Stream stream, TransportContext context, CancellationToken cancellationToken) | at System.Net.Http.HttpContent.LoadIntoBufferAsyncCore(Task serializeToStreamTask, MemoryStream tempBuffer) | --- End of inner exception stack trace --- | at System.Net.Http.HttpContent.LoadIntoBufferAsyncCore(Task serializeToStreamTask, MemoryStream tempBuffer) | at System.Net.Http.HttpContent.WaitAndReturnAsync[TState,TResult](Task waitTask, TState state, Func`2 returnFunc) | at Interop.FunctionalTests.Http3.Http3RequestTests.GET_GracefulServerShutdown_RequestCompleteSuccessfullyInsideHostTimeout(HttpProtocols protocol) in /_/src/Servers/Kestrel/test/Interop.FunctionalTests/Http3/Http3RequestTests.cs:line 1630 | at Xunit.Sdk.TestInvoker`1.<>c__DisplayClass48_1.<b__1>d.MoveNext() in C:\Dev\xunit\xunit\src\xunit.execution\Sdk\Frameworks\Runners\TestInvoker.cs:line 273 | --- End of stack trace from previous location --- | at Xunit.Sdk.ExecutionTimer.AggregateAsync(Func`1 asyncAction) in C:\Dev\xunit\xunit\src\xunit.execution\Sdk\Frameworks\ExecutionTimer.cs:line 54 | at Xunit.Sdk.ExceptionAggregator.RunAsync(Func`1 code) in C:\Dev\xunit\xunit\src\xunit.core\Sdk\ExceptionAggregator.cs:line 96 | [0.366s] TestLifetime Information: Finished test GET_GracefulServerShutdown_RequestCompleteSuccessfullyInsideHostTimeout-Http2 in 0.364772s ```

Build

https://dev.azure.com/dnceng/public/_build/results?buildId=1443461&view=ms.vss-test-web.build-test-results-tab&runId=41616286&resultId=109117&paneView=debug

HaoK commented 3 years ago

@wtgodbe do you want me to dupe this against the other one? [QuarantinedTest("https://github.com/dotnet/aspnetcore/issues/35070")]

wtgodbe commented 3 years ago

Yeah, and add the failure info to the other issue

wtgodbe commented 3 years ago

@HaoK do you have a PR to quarantine the test?