Open
Description
Failing Test(s)
Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.NamedPipeConnectionListenerTests.AcceptAsync_ParallelConnections_ClientConnectionsSuccessfullyAcceptedMicrosoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.WebHostTests.ListenNamedPipeEndpoint_HelloWorld_ClientSuccess
Error Message
System.TimeoutException : The operation has timed out.
Stacktrace
2025-03-17T18:56:30.4899885Z [xUnit.net 00:00:43.39] Output:
2025-03-17T18:56:30.4902162Z [xUnit.net 00:00:43.39] | [0.001s] TestLifetime Information: Starting test OutputWriteAsync_ServerAborted_ThrowError at 2025-03-17T18:56:00
2025-03-17T18:56:30.4904288Z [xUnit.net 00:00:43.39] | [12.832s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLHVL" accepted.
2025-03-17T18:56:30.4906399Z [xUnit.net 00:00:43.39] | [13.845s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLHVL" disconnecting stream because: "Test reason"
2025-03-17T18:56:30.4908661Z [xUnit.net 00:00:43.39] | [13.857s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLHVL" received end of stream.
2025-03-17T18:56:30.4910858Z [xUnit.net 00:00:43.39] | [30.257s] TestLifetime Information: Finished test OutputWriteAsync_ServerAborted_ThrowError in 30.2562626s
2025-03-17T18:56:30.4914185Z Passed Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.NamedPipeConnectionTests.OutputWriteAsync_ServerAborted_ThrowError [21 s]
2025-03-17T18:56:32.9809772Z [xUnit.net 00:00:45.88] System.TimeoutException : The operation has timed out.
2025-03-17T18:56:32.9875024Z [xUnit.net 00:00:45.88] Stack Trace:
2025-03-17T18:56:32.9905936Z [xUnit.net 00:00:45.88] /_/src/Shared/TaskExtensions.cs(123,0): at Microsoft.AspNetCore.InternalTesting.TaskExtensions.TimeoutAfter(Task task, TimeSpan timeout, String filePath, Int32 lineNumber)
2025-03-17T18:56:32.9938162Z [xUnit.net 00:00:45.88] /_/src/Servers/Kestrel/Transport.NamedPipes/test/NamedPipeConnectionListenerTests.cs(200,0): at Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.NamedPipeConnectionListenerTests.AcceptAsync_ParallelConnections_ClientConnectionsSuccessfullyAccepted(Int32 listenerQueueCount)
2025-03-17T18:56:32.9980235Z [xUnit.net 00:00:45.88] /_/src/Servers/Kestrel/Transport.NamedPipes/test/NamedPipeConnectionListenerTests.cs(203,0): at Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.NamedPipeConnectionListenerTests.AcceptAsync_ParallelConnections_ClientConnectionsSuccessfullyAccepted(Int32 listenerQueueCount)
2025-03-17T18:56:33.0016787Z [xUnit.net 00:00:45.88] --- End of stack trace from previous location ---
2025-03-17T18:56:33.0043520Z [xUnit.net 00:00:45.88] Output:
2025-03-17T18:56:33.0050502Z [xUnit.net 00:00:45.88] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.NamedPipeConnectionListenerTests.AcceptAsync_ParallelConnections_ClientConnectionsSuccessfullyAccepted(listenerQueueCount: 4) [FAIL]
2025-03-17T18:56:33.0114476Z [xUnit.net 00:00:45.88] | [0.001s] TestLifetime Information: Starting test AcceptAsync_ParallelConnections_ClientConnectionsSuccessfullyAccepted-4 at 2025-03-17T18:56:00
2025-03-17T18:56:33.0277730Z [xUnit.net 00:00:45.88] | [0.069s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLHVF" received end of stream.
2025-03-17T18:56:33.0308977Z [xUnit.net 00:00:45.88] | [0.070s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLHVG" received end of stream.
2025-03-17T18:56:33.0491054Z [xUnit.net 00:00:45.88] | [0.070s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLHVH" received end of stream.
2025-03-17T18:56:33.0498314Z [xUnit.net 00:00:45.88] | [0.071s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLHVI" received end of stream.
2025-03-17T18:56:33.0598660Z [xUnit.net 00:00:45.88] | [0.073s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLHVJ" received end of stream.
2025-03-17T18:56:33.0626235Z [xUnit.net 00:00:45.88] | [12.832s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLHVF" accepted.
2025-03-17T18:56:33.1207034Z [xUnit.net 00:00:45.88] | [12.832s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.NamedPipeConnectionListenerTests Information: Server accepted 1 calls.
2025-03-17T18:56:33.1544995Z [xUnit.net 00:00:45.88] | [14.843s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLHVM" received end of stream.
2025-03-17T18:56:33.1589951Z [xUnit.net 00:00:45.88] | [14.851s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLHVG" accepted.
2025-03-17T18:56:33.1651294Z [xUnit.net 00:00:45.88] | [14.851s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.NamedPipeConnectionListenerTests Information: Server accepted 2 calls.
2025-03-17T18:56:33.1692419Z [xUnit.net 00:00:45.88] | [14.851s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLHVN" received end of stream.
2025-03-17T18:56:33.1709920Z [xUnit.net 00:00:45.88] | [14.851s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLHVM" accepted.
2025-03-17T18:56:33.1742734Z [xUnit.net 00:00:45.88] | [14.851s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.NamedPipeConnectionListenerTests Information: Server accepted 3 calls.
2025-03-17T18:56:33.1754576Z [xUnit.net 00:00:45.88] | [14.852s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLHVO" received end of stream.
2025-03-17T18:56:33.1776446Z [xUnit.net 00:00:45.88] | [14.852s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLHVN" accepted.
2025-03-17T18:56:33.1809141Z [xUnit.net 00:00:45.88] | [14.852s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.NamedPipeConnectionListenerTests Information: Server accepted 4 calls.
2025-03-17T18:56:33.1810839Z [xUnit.net 00:00:45.88] | [14.852s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLHVP" received end of stream.
2025-03-17T18:56:33.1813058Z [xUnit.net 00:00:45.88] | [14.852s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLHVO" accepted.
2025-03-17T18:56:33.1815205Z [xUnit.net 00:00:45.88] | [14.852s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.NamedPipeConnectionListenerTests Information: Server accepted 5 calls.
...
2025-03-17T18:56:34.4872036Z [xUnit.net 00:00:46.57] | [30.329s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.NamedPipeConnectionListenerTests Information: Server accepted 2500 calls.
2025-03-17T18:56:34.4873948Z [xUnit.net 00:00:46.57] | [30.329s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.NamedPipeConnectionListenerTests Information: Server task complete.
2025-03-17T18:56:34.4875728Z [xUnit.net 00:00:46.57] | [32.748s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.NamedPipeConnectionListenerTests Error: Test threw an exception.
2025-03-17T18:56:34.4877518Z [xUnit.net 00:00:46.57] | System.TimeoutException: The operation has timed out.
2025-03-17T18:56:34.4879373Z [xUnit.net 00:00:46.57] | at Microsoft.AspNetCore.InternalTesting.TaskExtensions.TimeoutAfter(Task task, TimeSpan timeout, String filePath, Int32 lineNumber) in /_/src/Shared/TaskExtensions.cs:line 123
2025-03-17T18:56:34.4881256Z [xUnit.net 00:00:46.57] | at Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.NamedPipeConnectionListenerTests.AcceptAsync_ParallelConnections_ClientConnectionsSuccessfullyAccepted(Int32 listenerQueueCount) in /_/src/Servers/Kestrel/Transport.NamedPipes/test/NamedPipeConnectionListenerTests.cs:line 200
2025-03-17T18:56:34.4883032Z [xUnit.net 00:00:46.57] | at Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.NamedPipeConnectionListenerTests.AcceptAsync_ParallelConnections_ClientConnectionsSuccessfullyAccepted(Int32 listenerQueueCount) in /_/src/Servers/Kestrel/Transport.NamedPipes/test/NamedPipeConnectionListenerTests.cs:line 203
2025-03-17T18:56:34.4885955Z [xUnit.net 00:00:46.57] | at Xunit.Sdk.TestInvoker`1.<>c__DisplayClass47_0.<<InvokeTestMethodAsync>b__1>d.MoveNext() in /_/src/xunit.execution/Sdk/Frameworks/Runners/TestInvoker.cs:line 259
2025-03-17T18:56:34.4887659Z [xUnit.net 00:00:46.57] | --- End of stack trace from previous location ---
2025-03-17T18:56:34.4889477Z [xUnit.net 00:00:46.57] | at Xunit.Sdk.ExecutionTimer.AggregateAsync(Func`1 asyncAction) in /_/src/xunit.execution/Sdk/Frameworks/ExecutionTimer.cs:line 48
2025-03-17T18:56:34.4891164Z [xUnit.net 00:00:46.57] | at Xunit.Sdk.ExceptionAggregator.RunAsync(Func`1 code) in /_/src/xunit.core/Sdk/ExceptionAggregator.cs:line 90
2025-03-17T18:56:34.4892853Z [xUnit.net 00:00:46.57] | [32.749s] TestLifetime Information: Finished test AcceptAsync_ParallelConnections_ClientConnectionsSuccessfullyAccepted-4 in 32.7476194s
2025-03-17T18:56:34.4899084Z Failed Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.NamedPipeConnectionListenerTests.AcceptAsync_ParallelConnections_ClientConnectionsSuccessfullyAccepted(listenerQueueCount: 4) [32 s]
2025-03-17T18:56:34.4900331Z Error Message:
2025-03-17T18:56:34.4901950Z System.TimeoutException : The operation has timed out.
2025-03-17T18:56:34.4903513Z Stack Trace:
2025-03-17T18:56:34.4904379Z at Microsoft.AspNetCore.InternalTesting.TaskExtensions.TimeoutAfter(Task task, TimeSpan timeout, String filePath, Int32 lineNumber) in /_/src/Shared/TaskExtensions.cs:line 123
2025-03-17T18:56:34.4904579Z at Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.NamedPipeConnectionListenerTests.AcceptAsync_ParallelConnections_ClientConnectionsSuccessfullyAccepted(Int32 listenerQueueCount) in /_/src/Servers/Kestrel/Transport.NamedPipes/test/NamedPipeConnectionListenerTests.cs:line 200
2025-03-17T18:56:34.4904677Z at Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.NamedPipeConnectionListenerTests.AcceptAsync_ParallelConnections_ClientConnectionsSuccessfullyAccepted(Int32 listenerQueueCount) in /_/src/Servers/Kestrel/Transport.NamedPipes/test/NamedPipeConnectionListenerTests.cs:line 203
2025-03-17T18:56:34.4904758Z --- End of stack trace from previous location ---
2025-03-17T18:56:34.5280930Z [xUnit.net 00:00:47.21] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.WebHostTests.ListenNamedPipeEndpoint_HelloWorld_ClientSuccess [FAIL]
2025-03-17T18:56:34.5282720Z [xUnit.net 00:00:47.21] System.TimeoutException : The operation has timed out.
2025-03-17T18:56:34.5284590Z [xUnit.net 00:00:47.21] Stack Trace:
2025-03-17T18:56:34.5286614Z [xUnit.net 00:00:47.21] /_/src/Shared/TaskExtensions.cs(88,0): at Microsoft.AspNetCore.InternalTesting.TaskExtensions.TimeoutAfter[T](Task`1 task, TimeSpan timeout, String filePath, Int32 lineNumber)
2025-03-17T18:56:34.5288457Z [xUnit.net 00:00:47.21] /_/src/Servers/Kestrel/Transport.NamedPipes/test/WebHostTests.cs(174,0): at Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.WebHostTests.ListenNamedPipeEndpoint_HelloWorld_ClientSuccess()
2025-03-17T18:56:34.5290249Z [xUnit.net 00:00:47.21] --- End of stack trace from previous location ---
2025-03-17T18:56:34.5292095Z [xUnit.net 00:00:47.21] Output:
2025-03-17T18:56:34.5293890Z [xUnit.net 00:00:47.21] | [0.011s] TestLifetime Information: Starting test ListenNamedPipeEndpoint_HelloWorld_ClientSuccess at 2025-03-17T18:55:50
2025-03-17T18:56:34.5295735Z [xUnit.net 00:00:47.21] | [4.567s] HttpEventSourceListener Debug: Starting HttpEventSourceListener.
2025-03-17T18:56:34.5297712Z [xUnit.net 00:00:47.21] | [8.075s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Quic - Info : (null),.cctor,Attempting to load MsQuic from C:\h\w\A43F0996\p\dotnet-cli\shared\Microsoft.NETCore.App\10.0.0-preview.3.25166.3\msquic.dll ->
2025-03-17T18:56:34.5299662Z [xUnit.net 00:00:47.21] | [8.105s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Quic - Info : (null),.cctor,Loaded MsQuic library 'msquic.dll 2.4.8.569855 (5ddc9bff4e82e910872fd239340e7970e3c768f8)'. ->
2025-03-17T18:56:34.5301492Z [xUnit.net 00:00:47.21] | [9.178s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - Info : SocketsHttpHandler#47111983,.ctor, ->
2025-03-17T18:56:34.5304947Z [xUnit.net 00:00:47.21] | [9.179s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - Associate : HttpClient#42380069,.ctor,HttpClient#42380069,SocketsHttpHandler#47111983 ->
2025-03-17T18:56:34.5306859Z [xUnit.net 00:00:47.21] | [9.184s] Microsoft.Extensions.Hosting.Internal.Host Debug: Hosting starting
2025-03-17T18:56:34.5308677Z [xUnit.net 00:00:47.21] | [9.636s] Microsoft.Hosting.Lifetime Information: Now listening on: http://pipe:/Kestrel-nktur0j4.35u
2025-03-17T18:56:34.5310651Z [xUnit.net 00:00:47.21] | [9.637s] Microsoft.AspNetCore.Hosting.Diagnostics Debug: Loaded hosting startup assembly Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests
2025-03-17T18:56:34.5312735Z [xUnit.net 00:00:47.21] | [9.637s] Microsoft.Hosting.Lifetime Information: Application started. Press Ctrl+C to shut down.
2025-03-17T18:56:34.5314741Z [xUnit.net 00:00:47.21] | [9.638s] Microsoft.Hosting.Lifetime Information: Hosting environment: Production
2025-03-17T18:56:34.5316689Z [xUnit.net 00:00:47.21] | [9.638s] Microsoft.Hosting.Lifetime Information: Content root path: C:\h\w\A43F0996\w\A9F809B8\e\
2025-03-17T18:56:34.5318601Z [xUnit.net 00:00:47.21] | [9.638s] Microsoft.Extensions.Hosting.Internal.Host Debug: Hosting started
2025-03-17T18:56:34.5320527Z [xUnit.net 00:00:47.21] | [9.641s] HttpEventSourceListener Debug: <- Event System.Net.Http - RequestStart : http,127.0.0.1,80,/,1,1,2 ->
2025-03-17T18:56:34.5322539Z [xUnit.net 00:00:47.21] | [9.731s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - Info : WinInetProxyHelper#7615272,.ctor,AutoConfigUrl=(null), AutoDetect=True, Proxy=(null), ProxyBypass=(null) ->
2025-03-17T18:56:34.5325014Z [xUnit.net 00:00:47.21] | [9.731s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - Info : WinInetProxyHelper#7615272,.ctor,_useProxy=True ->
2025-03-17T18:56:34.5327398Z [xUnit.net 00:00:47.21] | [9.731s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - Info : WinInetProxyHelper#7615272,UpdateConfiguration,AutoSettingsUsed, calling WinHttpOpen ->
2025-03-17T18:56:34.5329525Z [xUnit.net 00:00:47.21] | [9.732s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - Info : HttpConnectionHandler#25230691,.ctor, ->
2025-03-17T18:56:34.5331665Z [xUnit.net 00:00:47.21] | [9.732s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - Info : MetricsHandler#29382239,.ctor, ->
2025-03-17T18:56:34.5333821Z [xUnit.net 00:00:47.21] | [9.733s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - Info : DiagnosticsHandler#30797404,.ctor, ->
2025-03-17T18:56:34.5335758Z [xUnit.net 00:00:47.21] | [9.736s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - Info : RedirectHandler#55674266,.ctor, ->
2025-03-17T18:56:34.5337606Z [xUnit.net 00:00:47.21] | [9.804s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - ErrorMessage : WinInetProxyHelper#7615272,GetProxyForUrl,error=12180 ->
2025-03-17T18:56:34.5339521Z [xUnit.net 00:00:47.21] | [9.804s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - Info : WinInetProxyHelper#7615272,GetProxyForUrl,useProxy=False ->
2025-03-17T18:56:34.5341603Z [xUnit.net 00:00:47.21] | [9.808s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - HandlerMessage : 62057562,0,0,.ctor,HttpConnectionPool http://127.0.0.1:80 ->
2025-03-17T18:56:34.5343886Z [xUnit.net 00:00:47.21] | [9.810s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - HandlerMessage : 62057562,0,0,CheckForHttp11ConnectionInjection,Available HTTP/1.1 connections: 0, Requests in the queue: 1, Requests without a connection attempt: 1, Pending HTTP/1.1 connections: 0, Total associated HTTP/1.1 connections: 0, Max HTTP/1.1 connection limit: 2147483647, Will inject connection: True. ->
2025-03-17T18:56:34.5346047Z [xUnit.net 00:00:47.21] | [9.810s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - HandlerMessage : 62057562,0,0,InjectNewHttp11ConnectionAsync,Creating new HTTP/1.1 connection for pool. ->
2025-03-17T18:56:34.5348043Z [xUnit.net 00:00:47.21] | [9.829s] HttpEventSourceListener Debug: <- Event System.Net.Http - ConnectionEstablished : 1,1,0,http,127.0.0.1,80, ->
2025-03-17T18:56:34.5350002Z [xUnit.net 00:00:47.21] | [9.830s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - HandlerMessage : 62057562,7209690,0,TraceConnection,HttpConnection(HttpConnectionPool http://127.0.0.1:80). Id:0 ->
2025-03-17T18:56:34.5352101Z [xUnit.net 00:00:47.21] | [9.830s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - HandlerMessage : 62057562,0,0,AddNewHttp11Connection, ->
2025-03-17T18:56:34.5354072Z [xUnit.net 00:00:47.21] | [9.830s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - HandlerMessage : 62057562,7209690,0,TrySignal,Dequeued waiting request. ->
2025-03-17T18:56:34.5356068Z [xUnit.net 00:00:47.21] | [9.832s] HttpEventSourceListener Debug: <- Event System.Net.Http - RequestLeftQueue : 20.7148,1,1 ->
2025-03-17T18:56:34.5358144Z [xUnit.net 00:00:47.21] | [9.834s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - HandlerMessage : 62057562,7209690,65976667,SendAsync,Sending request: Method: GET, RequestUri: 'http://127.0.0.1/', Version: 1.1, Content: <null>, Headers:
2025-03-17T18:56:34.5360337Z [xUnit.net 00:00:47.21] | {
2025-03-17T18:56:34.5362408Z [xUnit.net 00:00:47.21] | } ->
2025-03-17T18:56:34.5364534Z [xUnit.net 00:00:47.21] | [9.834s] HttpEventSourceListener Debug: <- Event System.Net.Http - RequestHeadersStart : 0 ->
2025-03-17T18:56:34.5366681Z [xUnit.net 00:00:47.21] | [9.834s] HttpEventSourceListener Debug: <- Event System.Net.Http - RequestHeadersStop : ->
2025-03-17T18:56:34.5368954Z [xUnit.net 00:00:47.21] | [9.834s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - HandlerMessage : 62057562,7209690,65976667,WriteToStreamAsync,Writing 35 bytes. ->
2025-03-17T18:56:34.5371150Z [xUnit.net 00:00:47.21] | [22.381s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLHVK" accepted.
2025-03-17T18:56:34.5373113Z [xUnit.net 00:00:47.21] | [22.383s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HNB5GLDCLHVK" accepted.
2025-03-17T18:56:34.5375215Z [xUnit.net 00:00:47.21] | [22.418s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HNB5GLDCLHVK" started.
2025-03-17T18:56:34.5377192Z [xUnit.net 00:00:47.21] | [39.833s] Microsoft.AspNetCore.Hosting.Diagnostics Information: Request starting HTTP/1.1 GET http://127.0.0.1/ - - -
2025-03-17T18:56:34.5379000Z [xUnit.net 00:00:47.21] | [39.843s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - HandlerMessage : 62057562,7209690,65976667,RegisterCancellation,Cancellation requested. Disposing of the connection. ->
2025-03-17T18:56:34.5380957Z [xUnit.net 00:00:47.21] | [39.843s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - HandlerMessage : 62057562,7209690,65976667,Dispose,Connection closing. ->
2025-03-17T18:56:34.5383052Z [xUnit.net 00:00:47.21] | [39.843s] HttpEventSourceListener Debug: <- Event System.Net.Http - ConnectionClosed : 1,1,0 ->
2025-03-17T18:56:34.5385184Z [xUnit.net 00:00:47.21] | [39.843s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - HandlerMessage : 62057562,0,0,CheckForHttp11ConnectionInjection,Available HTTP/1.1 connections: 0, Requests in the queue: 0, Requests without a connection attempt: 0, Pending HTTP/1.1 connections: 0, Total associated HTTP/1.1 connections: 0, Max HTTP/1.1 connection limit: 2147483647, Will inject connection: False. ->
2025-03-17T18:56:34.5387281Z [xUnit.net 00:00:47.21] | [39.843s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - HandlerMessage : 62057562,0,0,Dispose,Disposing the pool. ->
2025-03-17T18:56:34.5389387Z [xUnit.net 00:00:47.21] | [39.843s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - HandlerMessage : 62057562,0,0,CheckForHttp11ConnectionInjection,Available HTTP/1.1 connections: 0, Requests in the queue: 0, Requests without a connection attempt: 0, Pending HTTP/1.1 connections: 0, Total associated HTTP/1.1 connections: 0, Max HTTP/1.1 connection limit: 2147483647, Will inject connection: False. ->
2025-03-17T18:56:34.5391440Z [xUnit.net 00:00:47.21] | [39.856s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - HandlerMessage : 62057562,7209690,65976667,InitialFillAsync,Received 0 bytes. ->
2025-03-17T18:56:34.5393333Z [xUnit.net 00:00:47.21] | [39.858s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - HandlerMessage : 62057562,7209690,65976667,SendAsync,Error sending request: System.Net.Http.HttpIOException: The response ended prematurely. (ResponseEnded)
2025-03-17T18:56:34.5395338Z [xUnit.net 00:00:47.21] | at System.Net.Http.HttpConnection.SendAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken) ->
2025-03-17T18:56:34.5397452Z [xUnit.net 00:00:47.21] | [39.860s] HttpEventSourceListener Debug: <- Event System.Net.Http - RequestFailed : The operation was canceled. ->
2025-03-17T18:56:34.5399419Z [xUnit.net 00:00:47.21] | [39.860s] HttpEventSourceListener Debug: <- Event System.Net.Http - RequestFailedDetailed : System.Threading.Tasks.TaskCanceledException: The operation was canceled.
2025-03-17T18:56:34.5401594Z [xUnit.net 00:00:47.21] | ---> System.Net.Http.HttpIOException: The response ended prematurely. (ResponseEnded)
2025-03-17T18:56:34.5403691Z [xUnit.net 00:00:47.21] | at System.Net.Http.HttpConnection.SendAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken)
2025-03-17T18:56:34.5405571Z [xUnit.net 00:00:47.21] | --- End of inner exception stack trace ---
2025-03-17T18:56:34.5407561Z [xUnit.net 00:00:47.21] | at System.Net.Http.HttpConnection.SendAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken)
2025-03-17T18:56:34.5409452Z [xUnit.net 00:00:47.21] | at System.Net.Http.HttpConnectionPool.SendWithVersionDetectionAndRetryAsync(HttpRequestMessage request, Boolean async, Boolean doRequestAuth, CancellationToken cancellationToken)
2025-03-17T18:56:34.5411607Z [xUnit.net 00:00:47.21] | at System.Net.Http.RedirectHandler.SendAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken)
2025-03-17T18:56:34.5413831Z [xUnit.net 00:00:47.21] | at System.Net.Http.HttpClient.<SendAsync>g__Core|83_0(HttpRequestMessage request, HttpCompletionOption completionOption, CancellationTokenSource cts, Boolean disposeCts, CancellationTokenSource pendingRequestsCts, CancellationToken originalCancellationToken) ->
2025-03-17T18:56:34.5416128Z [xUnit.net 00:00:47.21] | [39.861s] HttpEventSourceListener Debug: <- Event Private.InternalDiagnostics.System.Net.Http - ErrorMessage : HttpClient#42380069,HandleFailure,System.Threading.Tasks.TaskCanceledException: The operation was canceled.
2025-03-17T18:56:34.5418351Z [xUnit.net 00:00:47.21] | ---> System.Net.Http.HttpIOException: The response ended prematurely. (ResponseEnded)
2025-03-17T18:56:34.5420492Z [xUnit.net 00:00:47.21] | at System.Net.Http.HttpConnection.SendAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken)
2025-03-17T18:56:34.5422540Z [xUnit.net 00:00:47.21] | --- End of inner exception stack trace ---
2025-03-17T18:56:34.5424747Z [xUnit.net 00:00:47.21] | at System.Net.Http.HttpConnection.SendAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken)
2025-03-17T18:56:34.5426661Z [xUnit.net 00:00:47.21] | at System.Net.Http.HttpConnectionPool.SendWithVersionDetectionAndRetryAsync(HttpRequestMessage request, Boolean async, Boolean doRequestAuth, CancellationToken cancellationToken)
2025-03-17T18:56:34.5428607Z [xUnit.net 00:00:47.21] | at System.Net.Http.RedirectHandler.SendAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken)
2025-03-17T18:56:34.5430439Z [xUnit.net 00:00:47.21] | at System.Net.Http.HttpClient.<SendAsync>g__Core|83_0(HttpRequestMessage request, HttpCompletionOption completionOption, CancellationTokenSource cts, Boolean disposeCts, CancellationTokenSource pendingRequestsCts, CancellationToken originalCancellationToken) ->
2025-03-17T18:56:34.5432308Z [xUnit.net 00:00:47.21] | [39.861s] HttpEventSourceListener Debug: <- Event System.Net.Http - RequestStop : -1 ->
2025-03-17T18:56:34.5434203Z [xUnit.net 00:00:47.21] | [39.862s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLHVK" received end of stream.
2025-03-17T18:56:34.5438738Z [xUnit.net 00:00:47.21] | [39.881s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Some connections failed to close gracefully during server shutdown.
2025-03-17T18:56:34.5440703Z [xUnit.net 00:00:47.21] | [39.885s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLHVK" disconnecting stream because: "The connection was aborted because the server is shutting down and request processing didn't complete within the time specified by HostOptions.ShutdownTimeout."
2025-03-17T18:56:34.5442608Z [xUnit.net 00:00:47.21] | [40.899s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Some connections failed to abort during server shutdown.
2025-03-17T18:56:34.5444726Z [xUnit.net 00:00:47.21] | [40.901s] HttpEventSourceListener Debug: Stopping HttpEventSourceListener.
2025-03-17T18:56:34.5446925Z [xUnit.net 00:00:47.21] | [42.311s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HNB5GLDCLHVK" disconnecting.
2025-03-17T18:56:34.5449037Z [xUnit.net 00:00:47.21] | [42.315s] Microsoft.AspNetCore.Hosting.Diagnostics Information: Request finished HTTP/1.1 GET http://127.0.0.1/ - 200 - - 2482.2075ms
2025-03-17T18:56:34.5451312Z [xUnit.net 00:00:47.22] | [42.320s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HNB5GLDCLHVK" stopped.
2025-03-17T18:56:34.5453332Z [xUnit.net 00:00:47.22] | [42.322s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.WebHostTests Error: Test threw an exception.
2025-03-17T18:56:34.5455360Z [xUnit.net 00:00:47.22] | System.TimeoutException: The operation has timed out.
2025-03-17T18:56:34.5457225Z [xUnit.net 00:00:47.22] | at Microsoft.AspNetCore.InternalTesting.TaskExtensions.TimeoutAfter[T](Task`1 task, TimeSpan timeout, String filePath, Int32 lineNumber) in /_/src/Shared/TaskExtensions.cs:line 88
2025-03-17T18:56:34.5459178Z [xUnit.net 00:00:47.22] | at Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.WebHostTests.ListenNamedPipeEndpoint_HelloWorld_ClientSuccess() in /_/src/Servers/Kestrel/Transport.NamedPipes/test/WebHostTests.cs:line 174
2025-03-17T18:56:34.5462270Z [xUnit.net 00:00:47.22] | at Xunit.Sdk.TestInvoker`1.<>c__DisplayClass47_0.<<InvokeTestMethodAsync>b__1>d.MoveNext() in /_/src/xunit.execution/Sdk/Frameworks/Runners/TestInvoker.cs:line 259
2025-03-17T18:56:34.5464168Z [xUnit.net 00:00:47.22] | --- End of stack trace from previous location ---
2025-03-17T18:56:34.5466024Z [xUnit.net 00:00:47.22] | at Xunit.Sdk.ExecutionTimer.AggregateAsync(Func`1 asyncAction) in /_/src/xunit.execution/Sdk/Frameworks/ExecutionTimer.cs:line 48
2025-03-17T18:56:34.5468005Z [xUnit.net 00:00:47.22] | at Xunit.Sdk.ExceptionAggregator.RunAsync(Func`1 code) in /_/src/xunit.core/Sdk/ExceptionAggregator.cs:line 90
2025-03-17T18:56:34.5470139Z [xUnit.net 00:00:47.22] | [42.324s] TestLifetime Information: Finished test ListenNamedPipeEndpoint_HelloWorld_ClientSuccess in 42.3192544s
2025-03-17T18:56:34.5472112Z [xUnit.net 00:00:47.22] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.NamedPipeConnectionTests.InputReadAsync_ServerAbortedDuring_ThrowError [PASS]
2025-03-17T18:56:34.5474094Z [xUnit.net 00:00:47.22] Output:
2025-03-17T18:56:34.5476189Z [xUnit.net 00:00:47.22] | [0.001s] TestLifetime Information: Starting test InputReadAsync_ServerAbortedDuring_ThrowError at 2025-03-17T18:56:30
2025-03-17T18:56:34.5478437Z [xUnit.net 00:00:47.22] | [2.481s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLIOF" accepted.
2025-03-17T18:56:34.5480835Z [xUnit.net 00:00:47.22] | [2.481s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLIOF" disconnecting stream because: "Test reason"
2025-03-17T18:56:34.5483242Z [xUnit.net 00:00:47.22] | [2.483s] Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes Debug: Connection id "0HNB5GLDCLIOF" received end of stream.
2025-03-17T18:56:34.5485332Z [xUnit.net 00:00:47.22] | [2.570s] TestLifetime Information: Finished test InputReadAsync_ServerAbortedDuring_ThrowError in 2.5662943s
Logs
error : (NETCORE_ENGINEERING_TELEMETRY=Test) Test Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.NamedPipeConnectionListenerTests.AcceptAsync_ParallelConnections_ClientConnectionsSuccessfullyAccepted has failed. Check the Test tab or this console log: https://helix.dot.net/api/2019-06-17/jobs/45631d55-9f15-41e1-ba9c-0fc01bf2ecc2/workitems/Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests--net10.0/console
error : (NETCORE_ENGINEERING_TELEMETRY=Test) Test Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.WebHostTests.ListenNamedPipeEndpoint_HelloWorld_ClientSuccess has failed. Check the Test tab or this console log: https://helix.dot.net/api/2019-06-17/jobs/45631d55-9f15-41e1-ba9c-0fc01bf2ecc2/workitems/Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests--net10.0/console