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 ConnectionCanSendAndReceiveMessages #60992

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

Quarantine ConnectionCanSendAndReceiveMessages #60992

ilonatommy opened this issue Mar 18, 2025 · 1 comment
Labels
area-signalr Includes: SignalR clients and servers test-failure

Comments

@ilonatommy
Copy link
Member

Failing Test(s)

  • Microsoft.AspNetCore.SignalR.Tests.EndToEndTests.ConnectionCanSendAndReceiveMessages

Error Message

 System.Net.Http.HttpRequestException: Response status code does not indicate success: 404 (Not Found).

Stacktrace

2025-03-17T18:56:47.1914530Z [xUnit.net 00:00:38.34]     Microsoft.AspNetCore.SignalR.Tests.EndToEndTests.ConnectionCanSendAndReceiveMessages(transportType: LongPolling, requestedTransferFormat: Binary) [FAIL]
2025-03-17T18:56:47.1917098Z [xUnit.net 00:00:38.34]       1 error(s) logged.
2025-03-17T18:56:47.1919048Z [xUnit.net 00:00:38.34]       Microsoft.AspNetCore.Http.Connections.Client.Internal.LongPollingTransport - ErrorPolling - Error while polling 'http://127.0.0.1:58388/echo?id=ZuCXZjl5nRYWt09e1amTiA'.
2025-03-17T18:56:47.1920835Z [xUnit.net 00:00:38.34]       ===================
2025-03-17T18:56:47.1922733Z [xUnit.net 00:00:38.34]       System.Net.Http.HttpRequestException: Response status code does not indicate success: 404 (Not Found).
2025-03-17T18:56:47.1924773Z [xUnit.net 00:00:38.34]          at System.Net.Http.HttpResponseMessage.EnsureSuccessStatusCode()
2025-03-17T18:56:47.1927332Z [xUnit.net 00:00:38.34]          at Microsoft.AspNetCore.Http.Connections.Client.Internal.LongPollingTransport.Poll(Uri pollUrl, CancellationToken cancellationToken) in /_/src/SignalR/clients/csharp/Http.Connections.Client/src/Internal/LongPollingTransport.cs:line 179
2025-03-17T18:56:47.1929472Z [xUnit.net 00:00:38.34]          at System.IO.Pipelines.Pipe.GetReadResult(ReadResult& result)
2025-03-17T18:56:47.1931546Z [xUnit.net 00:00:38.34]          at System.IO.Pipelines.Pipe.GetReadAsyncResult()
2025-03-17T18:56:47.1933538Z [xUnit.net 00:00:38.34]          at System.IO.Pipelines.PipeReaderExtensions.ReadAsync(PipeReader pipeReader, Int32 numBytes) in D:\a\_work\1\s\src\SignalR\common\testassets\Tests.Utils\PipeReaderExtensions.cs:line 89
2025-03-17T18:56:47.1935572Z [xUnit.net 00:00:38.34]          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:47.1937724Z [xUnit.net 00:00:38.34]          at Microsoft.AspNetCore.SignalR.Tests.EndToEndTests.ConnectionCanSendAndReceiveMessages(HttpTransportType transportType, TransferFormat requestedTransferFormat) in /_/src/SignalR/server/SignalR/test/Microsoft.AspNetCore.SignalR.Tests/EndToEndTests.cs:line 293
2025-03-17T18:56:47.1940188Z [xUnit.net 00:00:38.34]          at Microsoft.AspNetCore.SignalR.Tests.EndToEndTests.ConnectionCanSendAndReceiveMessages(HttpTransportType transportType, TransferFormat requestedTransferFormat) in /_/src/SignalR/server/SignalR/test/Microsoft.AspNetCore.SignalR.Tests/EndToEndTests.cs:line 305
2025-03-17T18:56:47.1942303Z [xUnit.net 00:00:38.34]       ===================
2025-03-17T18:56:47.1944487Z [xUnit.net 00:00:38.34]       Stack Trace:
2025-03-17T18:56:47.1946798Z [xUnit.net 00:00:38.34]         D:\a\_work\1\s\src\Shared\SignalR\VerifyNoErrorScope.cs(47,0): at Microsoft.AspNetCore.SignalR.Tests.VerifyNoErrorsScope.Dispose()
2025-03-17T18:56:47.1948808Z [xUnit.net 00:00:38.34]         D:\a\_work\1\s\src\Shared\SignalR\InProcessTestServer.cs(161,0): at Microsoft.AspNetCore.SignalR.Tests.InProcessTestServer`1.DisposeAsync()
2025-03-17T18:56:47.1950886Z [xUnit.net 00:00:38.34]         D:\a\_work\1\s\src\Shared\SignalR\InProcessTestServer.cs(168,0): at Microsoft.AspNetCore.SignalR.Tests.InProcessTestServer`1.DisposeAsync()
2025-03-17T18:56:47.1953071Z [xUnit.net 00:00:38.34]         /_/src/SignalR/server/SignalR/test/Microsoft.AspNetCore.SignalR.Tests/EndToEndTests.cs(307,0): at Microsoft.AspNetCore.SignalR.Tests.EndToEndTests.ConnectionCanSendAndReceiveMessages(HttpTransportType transportType, TransferFormat requestedTransferFormat)
2025-03-17T18:56:47.1955569Z [xUnit.net 00:00:38.34]         --- End of stack trace from previous location ---
2025-03-17T18:56:47.1957865Z [xUnit.net 00:00:38.34]       Output:
2025-03-17T18:56:47.1960248Z [xUnit.net 00:00:38.34]         | [0.001s] TestLifetime Information: Starting test ConnectionCanSendAndReceiveMessages-LongPolling-Binary at 2025-03-17T18:56:27
2025-03-17T18:56:47.1962435Z [xUnit.net 00:00:38.34]         | [0.021s] Microsoft.AspNetCore.SignalR.Tests.InProcessTestServer Information: Starting test server...
2025-03-17T18:56:47.1964678Z [xUnit.net 00:00:38.34]         | [0.022s] Microsoft.Extensions.Hosting.Internal.Host Debug: Hosting starting
2025-03-17T18:56:47.1967047Z [xUnit.net 00:00:38.34]         | [0.023s] Microsoft.AspNetCore.DataProtection.KeyManagement.DefaultKeyResolver Debug: Repository contains no viable default key. Caller should generate a key with immediate activation.
2025-03-17T18:56:47.1969093Z [xUnit.net 00:00:38.34]         | [0.023s] Microsoft.AspNetCore.DataProtection.KeyManagement.KeyRingProvider Debug: Policy resolution states that a new key should be added to the key ring.
2025-03-17T18:56:47.1971615Z [xUnit.net 00:00:38.34]         | [0.026s] Microsoft.AspNetCore.DataProtection.KeyManagement.XmlKeyManager Information: Creating key {e63b8e09-6589-40ee-834f-53b325d99e9e} with creation date 2025-03-17 18:56:27Z, activation date 2025-03-17 18:56:27Z, and expiration date 2025-06-15 18:56:27Z.
2025-03-17T18:56:47.1973921Z [xUnit.net 00:00:38.34]         | [0.027s] Microsoft.AspNetCore.DataProtection.KeyManagement.XmlKeyManager Debug: Descriptor deserializer type for key {e63b8e09-6589-40ee-834f-53b325d99e9e} is 'Microsoft.AspNetCore.DataProtection.AuthenticatedEncryption.ConfigurationModel.AuthenticatedEncryptorDescriptorDeserializer, Microsoft.AspNetCore.DataProtection, Version=10.0.0.0, Culture=neutral, PublicKeyToken=adb9793829ddae60'.
2025-03-17T18:56:47.1976054Z [xUnit.net 00:00:38.34]         | [0.027s] Microsoft.AspNetCore.DataProtection.KeyManagement.XmlKeyManager Debug: No key escrow sink found. Not writing key {e63b8e09-6589-40ee-834f-53b325d99e9e} to escrow.
2025-03-17T18:56:47.1977855Z [xUnit.net 00:00:38.34]         | [0.027s] Microsoft.AspNetCore.DataProtection.KeyManagement.XmlKeyManager Warning: No XML encryptor configured. Key {e63b8e09-6589-40ee-834f-53b325d99e9e} may be persisted to storage in unencrypted form.
2025-03-17T18:56:47.1979741Z [xUnit.net 00:00:38.34]         | [0.027s] Microsoft.AspNetCore.DataProtection.KeyManagement.XmlKeyManager Debug: Key cache expiration token triggered by 'CreateNewKey' operation.
2025-03-17T18:56:47.1982131Z [xUnit.net 00:00:38.34]         | [0.027s] Microsoft.AspNetCore.DataProtection.KeyManagement.XmlKeyManager Debug: Found key {e63b8e09-6589-40ee-834f-53b325d99e9e}.
2025-03-17T18:56:47.1984674Z [xUnit.net 00:00:38.34]         | [0.027s] Microsoft.AspNetCore.DataProtection.KeyManagement.DefaultKeyResolver Debug: Considering key {e63b8e09-6589-40ee-834f-53b325d99e9e} with expiration date 2025-06-15 18:56:27Z as default key.
2025-03-17T18:56:47.1987198Z [xUnit.net 00:00:38.34]         | [0.027s] Microsoft.AspNetCore.DataProtection.AuthenticatedEncryption.CngCbcAuthenticatedEncryptorFactory Debug: Opening CNG algorithm 'AES' from provider '(null)' with chaining mode CBC.
2025-03-17T18:56:47.1989781Z [xUnit.net 00:00:38.34]         | [0.027s] Microsoft.AspNetCore.DataProtection.AuthenticatedEncryption.CngCbcAuthenticatedEncryptorFactory Debug: Opening CNG algorithm 'SHA256' from provider '(null)' with HMAC.
2025-03-17T18:56:47.1991927Z [xUnit.net 00:00:38.34]         | [0.027s] Microsoft.AspNetCore.DataProtection.KeyManagement.KeyRingProvider Debug: Using key {e63b8e09-6589-40ee-834f-53b325d99e9e} as the default key.
2025-03-17T18:56:47.1994062Z [xUnit.net 00:00:38.34]         | [0.028s] Microsoft.AspNetCore.DataProtection.Internal.DataProtectionHostedService Debug: Key ring with default key {e63b8e09-6589-40ee-834f-53b325d99e9e} was loaded during application startup.
2025-03-17T18:56:47.1996346Z [xUnit.net 00:00:38.34]         | [0.028s] Microsoft.AspNetCore.SignalR.Internal.DefaultHubProtocolResolver Debug: Registered SignalR Protocol: json, implemented by Microsoft.AspNetCore.SignalR.Protocol.JsonHubProtocol.
2025-03-17T18:56:47.1998375Z [xUnit.net 00:00:38.34]         | [0.122s] Microsoft.Hosting.Lifetime Information: Now listening on: http://127.0.0.1:58388
2025-03-17T18:56:47.2000564Z [xUnit.net 00:00:38.34]         | [0.122s] Microsoft.AspNetCore.Hosting.Diagnostics Debug: Loaded hosting startup assembly Microsoft.AspNetCore.SignalR.Tests
2025-03-17T18:56:47.2002679Z [xUnit.net 00:00:38.34]         | [0.122s] Microsoft.AspNetCore.Http.Connections.Internal.HttpConnectionManager Trace: Starting connection heartbeat.
2025-03-17T18:56:47.2004863Z [xUnit.net 00:00:38.34]         | [0.122s] Microsoft.Hosting.Lifetime Information: Application started. Press Ctrl+C to shut down.
2025-03-17T18:56:47.2007886Z [xUnit.net 00:00:38.34]         | [0.122s] Microsoft.Hosting.Lifetime Information: Hosting environment: Production
2025-03-17T18:56:47.2009895Z [xUnit.net 00:00:38.35]         | [0.123s] Microsoft.Hosting.Lifetime Information: Content root path: C:\h\w\A43F0996\w\AF70096C\e
2025-03-17T18:56:47.2011778Z [xUnit.net 00:00:38.35]         | [0.123s] Microsoft.Extensions.Hosting.Internal.Host Debug: Hosting started
2025-03-17T18:56:47.2013967Z [xUnit.net 00:00:38.35]         | [0.123s] Microsoft.AspNetCore.SignalR.Tests.InProcessTestServer Information: Test Server started
2025-03-17T18:56:47.2015964Z [xUnit.net 00:00:38.35]         | [0.123s] Microsoft.AspNetCore.SignalR.Tests.EndToEndTests Information: Starting connection to http://127.0.0.1:58388/echo
2025-03-17T18:56:47.2017874Z [xUnit.net 00:00:38.35]         | [0.123s] Microsoft.AspNetCore.Http.Connections.Client.HttpConnection Debug: Starting HttpConnection.
2025-03-17T18:56:47.2019925Z [xUnit.net 00:00:38.35]         | [0.123s] Microsoft.AspNetCore.Http.Connections.Client.HttpConnection Debug: Establishing connection with server at 'http://127.0.0.1:58388/echo'.
2025-03-17T18:56:47.2021942Z [xUnit.net 00:00:38.35]         | [0.123s] Microsoft.AspNetCore.Http.Connections.Client.Internal.LoggingHttpMessageHandler Trace: Sending HTTP request POST 'http://127.0.0.1:58388/echo/negotiate?negotiateVersion=1'.
2025-03-17T18:56:47.2023845Z [xUnit.net 00:00:38.35]         | [0.152s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HNB5GLLF7M99" accepted.
2025-03-17T18:56:47.2026136Z [xUnit.net 00:00:38.35]         | [0.153s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HNB5GLLF7M99" started.
2025-03-17T18:56:47.2031062Z [xUnit.net 00:00:38.35]         | [0.153s] Microsoft.AspNetCore.Hosting.Diagnostics Information: Request starting HTTP/1.1 POST http://127.0.0.1:58388/echo/negotiate?negotiateVersion=1 - - 0
2025-03-17T18:56:47.2058832Z [xUnit.net 00:00:38.35]         | [0.154s] Microsoft.AspNetCore.Routing.Matching.DfaMatcher Debug: 1 candidate(s) found for the request path '/echo/negotiate'
2025-03-17T18:56:47.2067467Z [xUnit.net 00:00:38.35]         | [0.154s] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware Debug: Request matched endpoint '/echo/negotiate'
2025-03-17T18:56:47.2138128Z [xUnit.net 00:00:38.35]         | [0.154s] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware Trace: The endpoint does not specify the IRequestSizeLimitMetadata.
2025-03-17T18:56:47.2201750Z [xUnit.net 00:00:38.35]         | [0.155s] Microsoft.AspNetCore.Authentication.JwtBearer.JwtBearerHandler Debug: AuthenticationScheme: Bearer was not authenticated.
2025-03-17T18:56:47.2204023Z [xUnit.net 00:00:38.35]         | [0.155s] Microsoft.AspNetCore.Routing.EndpointMiddleware Information: Executing endpoint '/echo/negotiate'
2025-03-17T18:56:47.2206037Z [xUnit.net 00:00:38.35]         | [0.155s] Microsoft.AspNetCore.Http.Connections.Internal.HttpConnectionManager Debug: New connection BKOIOT6GZjkKM63eQj8IPA created.
2025-03-17T18:56:47.2208416Z [xUnit.net 00:00:38.35]         | [0.155s] Microsoft.AspNetCore.Http.Connections.Internal.HttpConnectionDispatcher Debug: BKOIOT6GZjkKM63eQj8IPA - Sending negotiation response.
2025-03-17T18:56:47.2210618Z [xUnit.net 00:00:38.35]         | [0.155s] Microsoft.AspNetCore.Routing.EndpointMiddleware Information: Executed endpoint '/echo/negotiate'
2025-03-17T18:56:47.2212806Z [xUnit.net 00:00:38.35]         | [0.156s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HNB5GLLF7M99" completed keep alive response.
2025-03-17T18:56:47.2215095Z [xUnit.net 00:00:38.35]         | [0.156s] Microsoft.AspNetCore.Hosting.Diagnostics Information: Request finished HTTP/1.1 POST http://127.0.0.1:58388/echo/negotiate?negotiateVersion=1 - 200 316 application/json 2.5183ms
2025-03-17T18:56:47.2217231Z [xUnit.net 00:00:38.35]         | [0.156s] Microsoft.AspNetCore.Http.Connections.Client.HttpConnection Debug: Established connection 'BKOIOT6GZjkKM63eQj8IPA' with the server.
2025-03-17T18:56:47.2219458Z [xUnit.net 00:00:38.35]         | [0.157s] Microsoft.AspNetCore.Http.Connections.Client.HttpConnection Debug: Skipping transport WebSockets because it was disabled by the client.
2025-03-17T18:56:47.2221867Z [xUnit.net 00:00:38.35]         | [0.157s] Microsoft.AspNetCore.Http.Connections.Client.HttpConnection Debug: Skipping transport ServerSentEvents because it was disabled by the client.
2025-03-17T18:56:47.2223784Z [xUnit.net 00:00:38.35]         | [0.157s] Microsoft.AspNetCore.Http.Connections.Client.HttpConnection Debug: Starting transport 'LongPolling' with Url: http://127.0.0.1:58388/echo.
2025-03-17T18:56:47.2225684Z [xUnit.net 00:00:38.35]         | [0.161s] Microsoft.AspNetCore.Http.Connections.Client.Internal.LongPollingTransport Information: Starting transport. Transfer mode: Binary.
2025-03-17T18:56:47.2227415Z [xUnit.net 00:00:38.35]         | [0.182s] Microsoft.AspNetCore.Http.Connections.Client.Internal.LoggingHttpMessageHandler Trace: Sending HTTP request GET 'http://127.0.0.1:58388/echo?id=ZuCXZjl5nRYWt09e1amTiA'.
2025-03-17T18:56:47.2229479Z [xUnit.net 00:00:38.35]         | [0.185s] Microsoft.AspNetCore.Hosting.Diagnostics Information: Request starting HTTP/1.1 GET http://127.0.0.1:58388/echo?id=ZuCXZjl5nRYWt09e1amTiA - - -
2025-03-17T18:56:47.2231533Z [xUnit.net 00:00:38.35]         | [0.186s] Microsoft.AspNetCore.Routing.Matching.DfaMatcher Debug: 1 candidate(s) found for the request path '/echo'
2025-03-17T18:56:47.2233833Z [xUnit.net 00:00:38.35]         | [0.186s] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware Debug: Request matched endpoint '/echo'
2025-03-17T18:56:47.2236204Z [xUnit.net 00:00:38.35]         | [0.186s] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware Trace: The endpoint does not specify the IRequestSizeLimitMetadata.
2025-03-17T18:56:47.2238433Z [xUnit.net 00:00:38.35]         | [0.186s] Microsoft.AspNetCore.Authentication.JwtBearer.JwtBearerHandler Debug: AuthenticationScheme: Bearer was not authenticated.
2025-03-17T18:56:47.2240606Z [xUnit.net 00:00:38.35]         | [0.186s] Microsoft.AspNetCore.Routing.EndpointMiddleware Information: Executing endpoint '/echo'
2025-03-17T18:56:47.2242948Z [xUnit.net 00:00:38.35]         | [0.211s] Microsoft.AspNetCore.Http.Connections.Internal.HttpConnectionDispatcher Debug: BKOIOT6GZjkKM63eQj8IPA - Establishing new connection.
2025-03-17T18:56:47.2245030Z [xUnit.net 00:00:38.35]         | [0.212s] Microsoft.AspNetCore.Routing.EndpointMiddleware Information: Executed endpoint '/echo'
2025-03-17T18:56:47.2247000Z [xUnit.net 00:00:38.35]         | [0.212s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HNB5GLLF7M99" completed keep alive response.
2025-03-17T18:56:47.2249957Z [xUnit.net 00:00:38.35]         | [0.212s] Microsoft.AspNetCore.Hosting.Diagnostics Information: Request finished HTTP/1.1 GET http://127.0.0.1:58388/echo?id=ZuCXZjl5nRYWt09e1amTiA - 200 0 application/octet-stream 27.5338ms
2025-03-17T18:56:47.2251963Z [xUnit.net 00:00:38.35]         | [19.168s] Microsoft.AspNetCore.Http.Connections.Client.Internal.LongPollingTransport Debug: Starting receive loop.
2025-03-17T18:56:47.2254024Z [xUnit.net 00:00:38.35]         | [19.168s] Microsoft.AspNetCore.Http.Connections.Client.Internal.LoggingHttpMessageHandler Trace: Sending HTTP request GET 'http://127.0.0.1:58388/echo?id=ZuCXZjl5nRYWt09e1amTiA'.
2025-03-17T18:56:47.2256251Z [xUnit.net 00:00:38.35]         | [19.172s] Microsoft.AspNetCore.Http.Connections.Client.Internal.LongPollingTransport Debug: Starting the send loop.
2025-03-17T18:56:47.2258604Z [xUnit.net 00:00:38.35]         | [19.172s] Microsoft.AspNetCore.Http.Connections.Internal.HttpConnectionManager Trace: Connection BKOIOT6GZjkKM63eQj8IPA timed out.
2025-03-17T18:56:47.2260855Z [xUnit.net 00:00:38.35]         | [19.173s] Microsoft.AspNetCore.Hosting.Diagnostics Information: Request starting HTTP/1.1 GET http://127.0.0.1:58388/echo?id=ZuCXZjl5nRYWt09e1amTiA - - -
2025-03-17T18:56:47.2263165Z [xUnit.net 00:00:38.35]         | [19.179s] Microsoft.AspNetCore.Http.Connections.Internal.HttpConnectionContext Trace: Disposing connection BKOIOT6GZjkKM63eQj8IPA.
2025-03-17T18:56:47.2265325Z [xUnit.net 00:00:38.35]         | [19.179s] Microsoft.AspNetCore.Http.Connections.Internal.HttpConnectionContext Trace: Waiting for application to complete.
2025-03-17T18:56:47.2267541Z [xUnit.net 00:00:38.35]         | [19.180s] Microsoft.AspNetCore.Http.Connections.Client.HttpConnection Debug: Transport 'LongPolling' started.
2025-03-17T18:56:47.2269620Z [xUnit.net 00:00:38.35]         | [19.180s] Microsoft.AspNetCore.Http.Connections.Client.HttpConnection Information: HttpConnection Started.
2025-03-17T18:56:47.2271452Z [xUnit.net 00:00:38.35]         | [19.180s] Microsoft.AspNetCore.Routing.Matching.DfaMatcher Debug: 1 candidate(s) found for the request path '/echo'
2025-03-17T18:56:47.2273231Z [xUnit.net 00:00:38.35]         | [19.180s] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware Debug: Request matched endpoint '/echo'
2025-03-17T18:56:47.2274940Z [xUnit.net 00:00:38.35]         | [19.180s] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware Trace: The endpoint does not specify the IRequestSizeLimitMetadata.
2025-03-17T18:56:47.2277081Z [xUnit.net 00:00:38.35]         | [19.182s] Microsoft.AspNetCore.Http.Connections.Internal.HttpConnectionContext Trace: Application complete.
2025-03-17T18:56:47.2279303Z [xUnit.net 00:00:38.35]         | [19.182s] Microsoft.AspNetCore.Authentication.JwtBearer.JwtBearerHandler Debug: AuthenticationScheme: Bearer was not authenticated.
2025-03-17T18:56:47.2281791Z [xUnit.net 00:00:38.35]         | [19.183s] Microsoft.AspNetCore.Routing.EndpointMiddleware Information: Executing endpoint '/echo'
2025-03-17T18:56:47.2284093Z [xUnit.net 00:00:38.35]         | [19.183s] Microsoft.AspNetCore.Http.Connections.Internal.HttpConnectionDispatcher Debug: BKOIOT6GZjkKM63eQj8IPA - Connection BKOIOT6GZjkKM63eQj8IPA was disposed.
2025-03-17T18:56:47.2286356Z [xUnit.net 00:00:38.35]         | [19.183s] Microsoft.AspNetCore.Routing.EndpointMiddleware Information: Executed endpoint '/echo'
2025-03-17T18:56:47.2288481Z [xUnit.net 00:00:38.35]         | [19.184s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HNB5GLLF7M99" completed keep alive response.
2025-03-17T18:56:47.2290571Z [xUnit.net 00:00:38.35]         | [19.184s] Microsoft.AspNetCore.Hosting.Diagnostics Information: Request finished HTTP/1.1 GET http://127.0.0.1:58388/echo?id=ZuCXZjl5nRYWt09e1amTiA - 404 0 text/plain 10.8891ms
2025-03-17T18:56:47.2292667Z [xUnit.net 00:00:38.35]         | [19.184s] Microsoft.AspNetCore.Http.Connections.Internal.HttpConnectionManager Debug: Removing connection ZuCXZjl5nRYWt09e1amTiA from the list of connections.
2025-03-17T18:56:47.2294571Z [xUnit.net 00:00:38.35]         | [19.185s] Microsoft.AspNetCore.SignalR.Tests.EndToEndTests Information: Started connection to http://127.0.0.1:58388/echo
2025-03-17T18:56:47.2296440Z [xUnit.net 00:00:38.35]         | [19.186s] Microsoft.AspNetCore.Http.Connections.Client.Internal.LoggingHttpMessageHandler Warning: Unsuccessful HTTP response NotFound return from GET 'http://127.0.0.1:58388/echo?id=ZuCXZjl5nRYWt09e1amTiA'.
2025-03-17T18:56:47.2298316Z [xUnit.net 00:00:38.35]         | [19.192s] Microsoft.AspNetCore.SignalR.Tests.EndToEndTests Information: Sending 9 byte message
2025-03-17T18:56:47.2300352Z [xUnit.net 00:00:38.35]         | [19.192s] Microsoft.AspNetCore.SignalR.Tests.EndToEndTests Information: Sent message
2025-03-17T18:56:47.2302646Z [xUnit.net 00:00:38.35]         | [19.192s] Microsoft.AspNetCore.SignalR.Tests.EndToEndTests Information: Receiving message
2025-03-17T18:56:47.2304908Z [xUnit.net 00:00:38.35]         | [19.195s] Microsoft.AspNetCore.Http.Connections.Client.Internal.LongPollingTransport Trace: Poll response with status code 404 received from server. Content length: 0.
2025-03-17T18:56:47.2307108Z [xUnit.net 00:00:38.35]         | [19.199s] Microsoft.AspNetCore.Http.Connections.Client.Internal.LongPollingTransport Error: Error while polling 'http://127.0.0.1:58388/echo?id=ZuCXZjl5nRYWt09e1amTiA'.
2025-03-17T18:56:47.2309312Z [xUnit.net 00:00:38.35]         | System.Net.Http.HttpRequestException: Response status code does not indicate success: 404 (Not Found).
2025-03-17T18:56:47.2311525Z [xUnit.net 00:00:38.35]         |    at System.Net.Http.HttpResponseMessage.EnsureSuccessStatusCode()
2025-03-17T18:56:47.2313742Z [xUnit.net 00:00:38.35]         |    at Microsoft.AspNetCore.Http.Connections.Client.Internal.LongPollingTransport.Poll(Uri pollUrl, CancellationToken cancellationToken) in /_/src/SignalR/clients/csharp/Http.Connections.Client/src/Internal/LongPollingTransport.cs:line 179
2025-03-17T18:56:47.2315942Z [xUnit.net 00:00:38.35]         | [19.199s] Microsoft.AspNetCore.Http.Connections.Client.Internal.LongPollingTransport Debug: Receive loop stopped.
2025-03-17T18:56:47.2317986Z [xUnit.net 00:00:38.35]         | [19.200s] Microsoft.AspNetCore.Http.Connections.Client.Internal.LongPollingTransport Debug: Send loop canceled.
2025-03-17T18:56:47.2319729Z [xUnit.net 00:00:38.35]         | [19.201s] Microsoft.AspNetCore.Http.Connections.Client.Internal.LongPollingTransport Debug: Send loop stopped.
2025-03-17T18:56:47.2321475Z [xUnit.net 00:00:38.35]         | [19.201s] Microsoft.AspNetCore.SignalR.Tests.EndToEndTests Information: Test threw exception
2025-03-17T18:56:47.2323699Z [xUnit.net 00:00:38.35]         | System.Net.Http.HttpRequestException: Response status code does not indicate success: 404 (Not Found).
2025-03-17T18:56:47.2325796Z [xUnit.net 00:00:38.35]         |    at System.Net.Http.HttpResponseMessage.EnsureSuccessStatusCode()
2025-03-17T18:56:47.2328130Z [xUnit.net 00:00:38.35]         |    at Microsoft.AspNetCore.Http.Connections.Client.Internal.LongPollingTransport.Poll(Uri pollUrl, CancellationToken cancellationToken) in /_/src/SignalR/clients/csharp/Http.Connections.Client/src/Internal/LongPollingTransport.cs:line 179
2025-03-17T18:56:47.2330408Z [xUnit.net 00:00:38.35]         |    at System.IO.Pipelines.Pipe.GetReadResult(ReadResult& result)
2025-03-17T18:56:47.2332551Z [xUnit.net 00:00:38.35]         |    at System.IO.Pipelines.Pipe.GetReadAsyncResult()
2025-03-17T18:56:47.2334621Z [xUnit.net 00:00:38.35]         |    at System.IO.Pipelines.PipeReaderExtensions.ReadAsync(PipeReader pipeReader, Int32 numBytes) in D:\a\_work\1\s\src\SignalR\common\testassets\Tests.Utils\PipeReaderExtensions.cs:line 89
2025-03-17T18:56:47.2336730Z [xUnit.net 00:00:38.35]         |    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:47.2339195Z [xUnit.net 00:00:38.35]         |    at Microsoft.AspNetCore.SignalR.Tests.EndToEndTests.ConnectionCanSendAndReceiveMessages(HttpTransportType transportType, TransferFormat requestedTransferFormat) in /_/src/SignalR/server/SignalR/test/Microsoft.AspNetCore.SignalR.Tests/EndToEndTests.cs:line 293
2025-03-17T18:56:47.2341307Z [xUnit.net 00:00:38.35]         | [19.203s] Microsoft.AspNetCore.SignalR.Tests.EndToEndTests Information: Disposing Connection
2025-03-17T18:56:47.2343228Z [xUnit.net 00:00:38.35]         | [19.203s] Microsoft.AspNetCore.Http.Connections.Client.HttpConnection Debug: Disposing HttpConnection.
2025-03-17T18:56:47.2344967Z [xUnit.net 00:00:38.35]         | [19.205s] Microsoft.AspNetCore.Http.Connections.Client.Internal.LongPollingTransport Information: Transport is stopping.
2025-03-17T18:56:47.2346639Z [xUnit.net 00:00:38.35]         | [19.206s] Microsoft.AspNetCore.Http.Connections.Client.Internal.LongPollingTransport Debug: Transport stopped.
2025-03-17T18:56:47.2348755Z [xUnit.net 00:00:38.35]         | [19.206s] Microsoft.AspNetCore.Http.Connections.Client.HttpConnection Information: HttpConnection Disposed.
2025-03-17T18:56:47.2350933Z [xUnit.net 00:00:38.35]         | [19.206s] Microsoft.AspNetCore.SignalR.Tests.EndToEndTests Information: Disposed Connection
2025-03-17T18:56:47.2353252Z [xUnit.net 00:00:38.35]         | [19.207s] Microsoft.Extensions.Hosting.Internal.Host Debug: Hosting stopping
2025-03-17T18:56:47.2355484Z [xUnit.net 00:00:38.35]         | [19.208s] Microsoft.AspNetCore.Http.Connections.Internal.HttpConnectionManager Trace: Ending connection heartbeat.
2025-03-17T18:56:47.2357733Z [xUnit.net 00:00:38.35]         | [19.208s] Microsoft.Hosting.Lifetime Information: Application is shutting down...
2025-03-17T18:56:47.2360395Z [xUnit.net 00:00:38.35]         | [19.208s] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets Debug: Connection id "0HNB5GLLF7M99" received FIN.
2025-03-17T18:56:47.2362442Z [xUnit.net 00:00:38.35]         | [19.208s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HNB5GLLF7M99" disconnecting.
2025-03-17T18:56:47.2364544Z [xUnit.net 00:00:38.35]         | [19.208s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HNB5GLLF7M99" stopped.
2025-03-17T18:56:47.2366545Z [xUnit.net 00:00:38.35]         | [19.208s] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets Debug: Connection id "0HNB5GLLF7M99" sending FIN because: "The Socket transport's send loop completed gracefully."
2025-03-17T18:56:47.2368377Z [xUnit.net 00:00:38.35]         | [19.209s] Microsoft.AspNetCore.SignalR.Tests.InProcessTestServer Information: Test server shut down
2025-03-17T18:56:47.2370228Z [xUnit.net 00:00:38.35]         | [19.209s] Microsoft.Extensions.Hosting.Internal.Host Debug: Hosting stopped
2025-03-17T18:56:47.2372119Z [xUnit.net 00:00:38.35]         | [19.258s] Microsoft.AspNetCore.SignalR.Tests.EndToEndTests Error: Test threw an exception.
2025-03-17T18:56:47.2374105Z [xUnit.net 00:00:38.35]         | Xunit.Sdk.FailException: 1 error(s) logged.
2025-03-17T18:56:47.2376211Z [xUnit.net 00:00:38.35]         | Microsoft.AspNetCore.Http.Connections.Client.Internal.LongPollingTransport - ErrorPolling - Error while polling 'http://127.0.0.1:58388/echo?id=ZuCXZjl5nRYWt09e1amTiA'.
2025-03-17T18:56:47.2378451Z [xUnit.net 00:00:38.35]         | ===================
2025-03-17T18:56:47.2380822Z [xUnit.net 00:00:38.35]         | System.Net.Http.HttpRequestException: Response status code does not indicate success: 404 (Not Found).
2025-03-17T18:56:47.2382988Z [xUnit.net 00:00:38.35]         |    at System.Net.Http.HttpResponseMessage.EnsureSuccessStatusCode()
2025-03-17T18:56:47.2385329Z [xUnit.net 00:00:38.35]         |    at Microsoft.AspNetCore.Http.Connections.Client.Internal.LongPollingTransport.Poll(Uri pollUrl, CancellationToken cancellationToken) in /_/src/SignalR/clients/csharp/Http.Connections.Client/src/Internal/LongPollingTransport.cs:line 179
2025-03-17T18:56:47.2387430Z [xUnit.net 00:00:38.35]         |    at System.IO.Pipelines.Pipe.GetReadResult(ReadResult& result)
2025-03-17T18:56:47.2389540Z [xUnit.net 00:00:38.35]         |    at System.IO.Pipelines.Pipe.GetReadAsyncResult()
2025-03-17T18:56:47.2391303Z [xUnit.net 00:00:38.35]         |    at System.IO.Pipelines.PipeReaderExtensions.ReadAsync(PipeReader pipeReader, Int32 numBytes) in D:\a\_work\1\s\src\SignalR\common\testassets\Tests.Utils\PipeReaderExtensions.cs:line 89
2025-03-17T18:56:47.2393055Z [xUnit.net 00:00:38.36]         |    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:47.2394890Z [xUnit.net 00:00:38.36]         |    at Microsoft.AspNetCore.SignalR.Tests.EndToEndTests.ConnectionCanSendAndReceiveMessages(HttpTransportType transportType, TransferFormat requestedTransferFormat) in /_/src/SignalR/server/SignalR/test/Microsoft.AspNetCore.SignalR.Tests/EndToEndTests.cs:line 293
2025-03-17T18:56:47.2397984Z [xUnit.net 00:00:38.36]         |    at Microsoft.AspNetCore.SignalR.Tests.EndToEndTests.ConnectionCanSendAndReceiveMessages(HttpTransportType transportType, TransferFormat requestedTransferFormat) in /_/src/SignalR/server/SignalR/test/Microsoft.AspNetCore.SignalR.Tests/EndToEndTests.cs:line 305
2025-03-17T18:56:47.2400472Z [xUnit.net 00:00:38.36]         | ===================
2025-03-17T18:56:47.2402705Z [xUnit.net 00:00:38.36]         |    at Xunit.Assert.Fail(String message) in /_/src/xunit.assert/Asserts/FailAsserts.cs:line 38
2025-03-17T18:56:47.2404881Z [xUnit.net 00:00:38.36]         |    at Microsoft.AspNetCore.SignalR.Tests.VerifyNoErrorsScope.Dispose() in D:\a\_work\1\s\src\Shared\SignalR\VerifyNoErrorScope.cs:line 47
2025-03-17T18:56:47.2406929Z [xUnit.net 00:00:38.36]         |    at Microsoft.AspNetCore.SignalR.Tests.InProcessTestServer`1.DisposeAsync() in D:\a\_work\1\s\src\Shared\SignalR\InProcessTestServer.cs:line 161
2025-03-17T18:56:47.2409050Z [xUnit.net 00:00:38.36]         |    at Microsoft.AspNetCore.SignalR.Tests.InProcessTestServer`1.DisposeAsync() in D:\a\_work\1\s\src\Shared\SignalR\InProcessTestServer.cs:line 168
2025-03-17T18:56:47.2411257Z [xUnit.net 00:00:38.36]         |    at Microsoft.AspNetCore.SignalR.Tests.EndToEndTests.ConnectionCanSendAndReceiveMessages(HttpTransportType transportType, TransferFormat requestedTransferFormat) in /_/src/SignalR/server/SignalR/test/Microsoft.AspNetCore.SignalR.Tests/EndToEndTests.cs:line 307
2025-03-17T18:56:47.2413442Z [xUnit.net 00:00:38.36]         |    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:47.2415212Z [xUnit.net 00:00:38.36]         | --- End of stack trace from previous location ---
2025-03-17T18:56:47.2416926Z [xUnit.net 00:00:38.36]         |    at Xunit.Sdk.ExecutionTimer.AggregateAsync(Func`1 asyncAction) in /_/src/xunit.execution/Sdk/Frameworks/ExecutionTimer.cs:line 48
2025-03-17T18:56:47.2418970Z [xUnit.net 00:00:38.36]         |    at Xunit.Sdk.ExceptionAggregator.RunAsync(Func`1 code) in /_/src/xunit.core/Sdk/ExceptionAggregator.cs:line 90

Logs

error : (NETCORE_ENGINEERING_TELEMETRY=Test) Test Microsoft.AspNetCore.SignalR.Tests.EndToEndTests.ConnectionCanSendAndReceiveMessages 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.SignalR.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-signalr Includes: SignalR clients and servers label Mar 18, 2025
@ilonatommy
Copy link
Member Author

Closing in favor of dotnet/dnceng#5278.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
area-signalr Includes: SignalR clients and servers test-failure
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
1 participant
@ilonatommy