Open
Description
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