Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Quarantine Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests #60991

Closed
ilonatommy opened this issue Mar 18, 2025 · 1 comment
Closed

Quarantine Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests #60991

ilonatommy opened this issue Mar 18, 2025 · 1 comment
Labels
area-networking Includes servers, yarp, json patch, bedrock, websockets, http client factory, and http abstractions test-failure

Comments

@ilonatommy
Copy link
Member

Failing Test(s)

  • Microsoft.AspNetCore.Server.Kestrel.Transport.NamedPipes.Tests.NamedPipeConnectionListenerTests.AcceptAsync_ParallelConnections_ClientConnectionsSuccessfullyAccepted
  • Microsoft.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

Build

https://dev.azure.com/dnceng-public/public/_build/results?buildId=983039&view=logs&j=0932446f-c4a2-59c2-187c-ad542d294916
@dotnet-issue-labeler dotnet-issue-labeler bot added the area-networking Includes servers, yarp, json patch, bedrock, websockets, http client factory, and http abstractions label Mar 18, 2025
@ilonatommy
Copy link
Member Author

Closing in favor of dotnet/dnceng#5279.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
area-networking Includes servers, yarp, json patch, bedrock, websockets, http client factory, and http abstractions test-failure
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
1 participant
@ilonatommy