ServerCanAbortConnectionAfterUnobservedClose-Loopback test failure #37990
Description
Failing Test(s)
Microsoft.AspNetCore.Server.Kestrel.Libuv.FunctionalTests.RequestTests.ServerCanAbortConnectionAfterUnobservedClose(listenOptionsName: "Loopback")
Error Message
System.TimeoutException : The operation has timed out.
Stacktrace
at Microsoft.AspNetCore.Testing.TaskExtensions.TimeoutAfter(Task task, TimeSpan timeout, String filePath, Int32 lineNumber) in /_/src/Shared/TaskExtensions.cs:line 143
at Microsoft.AspNetCore.Server.Kestrel.Libuv.FunctionalTests.RequestTests.ServerCanAbortConnectionAfterUnobservedClose(String listenOptionsName) in /_/src/Servers/Kestrel/test/FunctionalTests/RequestTests.cs:line 804
at Microsoft.AspNetCore.Server.Kestrel.Libuv.FunctionalTests.RequestTests.ServerCanAbortConnectionAfterUnobservedClose(String listenOptionsName) in /_/src/Servers/Kestrel/test/FunctionalTests/RequestTests.cs:line 809
--- End of stack trace from previous location ---
Logs
[0.002s] [TestLifetime] [Information] Starting test ServerCanAbortConnectionAfterUnobservedClose-Loopback at 2021-10-30T12:50:06
[0.003s] [Microsoft.Extensions.Hosting.Internal.Host] [Debug] Hosting starting
[0.006s] [Microsoft.Hosting.Lifetime] [Information] Now listening on: http://127.0.0.1:56242
[0.006s] [Microsoft.AspNetCore.Hosting.Diagnostics] [Debug] Loaded hosting startup assembly Libuv.FunctionalTests
[0.006s] [Microsoft.Hosting.Lifetime] [Information] Application started. Press Ctrl+C to shut down.
[0.006s] [Microsoft.Hosting.Lifetime] [Information] Hosting environment: Production
[0.006s] [Microsoft.Hosting.Lifetime] [Information] Content root path: /private/tmp/helix/working/B13209A2/w/ADC8097D/e/
[0.006s] [Microsoft.Extensions.Hosting.Internal.Host] [Debug] Hosting started
[0.006s] [Microsoft.AspNetCore.Server.Kestrel] [Debug] TestServer is listening on port 56242
[0.007s] [Microsoft.AspNetCore.Server.Kestrel.Connections] [Debug] Connection id "0HMCRKF61AP2P" accepted.
[0.007s] [Microsoft.AspNetCore.Server.Kestrel.Transport.Libuv] [Debug] Connection id "0HMCRKF61AP2P" received FIN.
[0.007s] [Microsoft.AspNetCore.Server.Kestrel.Connections] [Debug] Connection id "0HMCRKF61AP2P" started.
[0.007s] [Microsoft.AspNetCore.Server.Kestrel.Connections] [Debug] Connection id "0HMCRKF61AP2P" disconnecting.
[0.007s] [Microsoft.AspNetCore.Server.Kestrel.Transport.Libuv] [Debug] Connection id "0HMCRKF61AP2P" sending FIN because: "The client closed the connection."
[0.008s] [Microsoft.AspNetCore.Server.Kestrel.Connections] [Debug] Connection id "0HMCRKF61AP2P" stopped.
[30.016s] [Microsoft.Extensions.Hosting.Internal.Host] [Debug] Hosting stopping
[30.016s] [Microsoft.Hosting.Lifetime] [Information] Application is shutting down...
[30.016s] [Microsoft.Extensions.Hosting.Internal.Host] [Debug] Hosting stopped
[30.124s] [Microsoft.AspNetCore.Server.Kestrel.Libuv.FunctionalTests.RequestTests] [Error] Test threw an exception.
System.TimeoutException: The operation has timed out.
at Microsoft.AspNetCore.Testing.TaskExtensions.TimeoutAfter(Task task, TimeSpan timeout, String filePath, Int32 lineNumber) in /_/src/Shared/TaskExtensions.cs:line 143
at Microsoft.AspNetCore.Server.Kestrel.Libuv.FunctionalTests.RequestTests.ServerCanAbortConnectionAfterUnobservedClose(String listenOptionsName) in /_/src/Servers/Kestrel/test/FunctionalTests/RequestTests.cs:line 804
at Microsoft.AspNetCore.Server.Kestrel.Libuv.FunctionalTests.RequestTests.ServerCanAbortConnectionAfterUnobservedClose(String listenOptionsName) in /_/src/Servers/Kestrel/test/FunctionalTests/RequestTests.cs:line 809
at Xunit.Sdk.TestInvoker`1.<>c__DisplayClass48_1.<<InvokeTestMethodAsync>b__1>d.MoveNext() in C:\Dev\xunit\xunit\src\xunit.execution\Sdk\Frameworks\Runners\TestInvoker.cs:line 264
--- 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 48
at Xunit.Sdk.ExceptionAggregator.RunAsync(Func`1 code) in C:\Dev\xunit\xunit\src\xunit.core\Sdk\ExceptionAggregator.cs:line 96
[30.124s] [TestLifetime] [Information] Finished test ServerCanAbortConnectionAfterUnobservedClose-Loopback in 30.1215817s
Looks like the hang is because there was no "Connection id ... paused" log and the test uses that to set a TCS.