dotnet / dotnet/aspnetcore

Flaky test ChangingUserNameDuringReconnectLogsWarning

Open
#52,408 2 comments 0 reactions 0 assignees View on GitHub
area-signalr test-failure
Dominant language
C#
Stars
38.4k
Forks
10.9k
Avg merge
2d 6h
Merged PRs (30d)
290

Description

## Failing Test(s)

- Microsoft.AspNetCore.SignalR.Client.FunctionalTests.HubConnectionTests.ChangingUserNameDuringReconnectLogsWarning

## Error Message

```text
Assert.Fail(): 1 error(s) logged.
Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport - ErrorSendingMessage - Error while sending a message.

System.Net.WebSockets.WebSocketException (0x80004005): The remote party closed the WebSocket connection without completing the close handshake.
---> System.IO.IOException: Cannot access a closed stream.
at System.Net.WebSockets.ManagedWebSocket.SendFrameFallbackAsync(MessageOpcode opcode, Boolean endOfMessage, Boolean disableCompression, ReadOnlyMemory1 payloadBuffer, Task lockTask, CancellationToken cancellationToken) at System.Runtime.CompilerServices.AsyncMethodBuilderCore.Start[TStateMachine](TStateMachine& stateMachine) at System.Net.WebSockets.ManagedWebSocket.SendAsync(ReadOnlyMemory1 buffer, WebSocketMessageType messageType, WebSocketMessageFlags messageFlags, CancellationToken cancellationToken)
at Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport.StartSending(WebSocket socket, Boolean ignoreFirstCanceled)
at System.Threading.ExecutionContext.RunFromThreadPoolDispatchLoop(Thread threadPoolThread, ExecutionContext executionContext, ContextCallback callback, Object state)
at System.Runtime.CompilerServices.AsyncTaskMethodBuilder1.AsyncStateMachineBox1.MoveNext(Thread threadPoolThread)
at System.Threading.ThreadPoolWorkQueue.Dispatch()
at System.Threading.PortableThreadPool.WorkerThread.WorkerThreadStart()
--- End of stack trace from previous location ---
at System.Net.WebSockets.ManagedWebSocket.SendFrameFallbackAsync(MessageOpcode opcode, Boolean endOfMessage, Boolean disableCompression, ReadOnlyMemory1 payloadBuffer, Task lockTask, CancellationToken cancellationToken) at System.Net.WebSockets.ManagedWebSocket.SendFrameFallbackAsync(MessageOpcode opcode, Boolean endOfMessage, Boolean disableCompression, ReadOnlyMemory1 payloadBuffer, Task lockTask, CancellationToken cancellationToken)
at Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport.StartSending(WebSocket socket, Boolean ignoreFirstCanceled) in /_/src/SignalR/clients/csharp/Http.Connections.Client/src/Internal/WebSocketsTransport.cs:line 562
```

## Stacktrace

```text
at Microsoft.AspNetCore.SignalR.Tests.VerifyNoErrorsScope.Dispose() in D:\a\_work\1\s\src\Shared\SignalR\VerifyNoErrorScope.cs:line 47
at Microsoft.AspNetCore.SignalR.Tests.InProcessTestServer`1.DisposeAsync()
at Microsoft.AspNetCore.SignalR.Tests.InProcessTestServer`1.DisposeAsync() in D:\a\_work\1\s\src\Shared\SignalR\InProcessTestServer.cs:line 163
at Microsoft.AspNetCore.SignalR.Client.FunctionalTests.HubConnectionTests.ChangingUserNameDuringReconnectLogsWarning() in /_/src/SignalR/clients/csharp/Client/test/FunctionalTests/HubConnectionTests.cs:line 2740
```

## Logs

```text
2023-11-27T18:32:45.6844128Z [xUnit.net 00:00:57.12] Microsoft.AspNetCore.SignalR.Client.FunctionalTests.HubConnectionTests.ChangingUserNameDuringReconnectLogsWarning [FAIL]
2023-11-27T18:32:45.6989300Z [xUnit.net 00:00:57.17] Stack Trace:
2023-11-27T18:32:45.6989423Z [xUnit.net 00:00:57.17] D:\a\_work\1\s\src\Shared\SignalR\VerifyNoErrorScope.cs(47,0): at Microsoft.AspNetCore.SignalR.Tests.VerifyNoErrorsScope.Dispose()
2023-11-27T18:32:45.6991374Z [xUnit.net 00:00:57.17] at Microsoft.AspNetCore.SignalR.Tests.InProcessTestServer`1.DisposeAsync()
2023-11-27T18:32:45.6991440Z [xUnit.net 00:00:57.19] D:\a\_work\1\s\src\Shared\SignalR\InProcessTestServer.cs(163,0): at Microsoft.AspNetCore.SignalR.Tests.InProcessTestServer`1.DisposeAsync()
2023-11-27T18:32:45.6991469Z [xUnit.net 00:00:57.19] /_/src/SignalR/clients/csharp/Client/test/FunctionalTests/HubConnectionTests.cs(2740,0): at Microsoft.AspNetCore.SignalR.Client.FunctionalTests.HubConnectionTests.ChangingUserNameDuringReconnectLogsWarning()
2023-11-27T18:32:45.6991518Z [xUnit.net 00:00:57.19] --- End of stack trace from previous location ---
2023-11-27T18:32:45.6991543Z [xUnit.net 00:00:57.19] Output:
2023-11-27T18:32:45.6991567Z [xUnit.net 00:00:57.19] | [0.000s] TestLifetime Information: Starting test ChangingUserNameDuringReconnectLogsWarning at 2023-11-27T18:32:45
2023-11-27T18:32:45.6991798Z [xUnit.net 00:00:57.19] | [0.002s] Microsoft.AspNetCore.SignalR.Tests.InProcessTestServer Information: Starting test server...
2023-11-27T18:32:45.6991842Z [xUnit.net 00:00:57.19] | [0.002s] Microsoft.Extensions.Hosting.Internal.Host Debug: Hosting starting
2023-11-27T18:32:45.6991874Z [xUnit.net 00:00:57.19] | [0.003s] Microsoft.AspNetCore.DataProtection.KeyManagement.DefaultKeyResolver Debug: Repository contains no viable default key. Caller should generate a key with immediate activation.
2023-11-27T18:32:45.6991905Z [xUnit.net 00:00:57.19] | [0.003s] Microsoft.AspNetCore.DataProtection.KeyManagement.KeyRingProvider Debug: Policy resolution states that a new key should be added to the key ring.
2023-11-27T18:32:45.6991939Z [xUnit.net 00:00:57.19] | [0.003s] Microsoft.AspNetCore.DataProtection.KeyManagement.XmlKeyManager Information: Creating key {7182e3d8-41fd-4d14-8423-9d40fbc37f75} with creation date 2023-11-27 18:32:45Z, activation date 2023-11-27 18:32:45Z, and expiration date 2024-02-25 18:32:45Z.
2023-11-27T18:32:45.6994244Z [xUnit.net 00:00:57.19] | [0.003s] Microsoft.AspNetCore.DataProtection.KeyManagement.XmlKeyManager Debug: Descriptor deserializer type for key {7182e3d8-41fd-4d14-8423-9d40fbc37f75} is 'Microsoft.AspNetCore.DataProtection.AuthenticatedEncryption.ConfigurationModel.AuthenticatedEncryptorDescriptorDeserializer, Microsoft.AspNetCore.DataProtection, Version=9.0.0.0, Culture=neutral, PublicKeyToken=adb9793829ddae60'.
2023-11-27T18:32:45.7287668Z [xUnit.net 00:00:57.19] | [0.003s] Microsoft.AspNetCore.DataProtection.KeyManagement.XmlKeyManager Debug: No key escrow sink found. Not writing key {7182e3d8-41fd-4d14-8423-9d40fbc37f75} to escrow.
2023-11-27T18:32:45.7343632Z [xUnit.net 00:00:57.19] | [0.003s] Microsoft.AspNetCore.DataProtection.KeyManagement.XmlKeyManager Warning: No XML encryptor configured. Key {7182e3d8-41fd-4d14-8423-9d40fbc37f75} may be persisted to storage in unencrypted form.
2023-11-27T18:32:45.7343805Z [xUnit.net 00:00:57.19] | [0.003s] Microsoft.AspNetCore.DataProtection.KeyManagement.XmlKeyManager Debug: Key cache expiration token triggered by 'CreateNewKey' operation.
2023-11-27T18:32:45.7343837Z [xUnit.net 00:00:57.19] | [0.003s] Microsoft.AspNetCore.DataProtection.KeyManagement.XmlKeyManager Debug: Found key {7182e3d8-41fd-4d14-8423-9d40fbc37f75}.
2023-11-27T18:32:45.7348655Z [xUnit.net 00:00:57.19] | [0.003s] Microsoft.AspNetCore.DataProtection.KeyManagement.DefaultKeyResolver Debug: Considering key {7182e3d8-41fd-4d14-8423-9d40fbc37f75} with expiration date 2024-02-25 18:32:45Z as default key.
2023-11-27T18:32:45.7349765Z [xUnit.net 00:00:57.19] | [0.003s] Microsoft.AspNetCore.DataProtection.TypeForwardingActivator Debug: Forwarded activator type request from Microsoft.AspNetCore.DataProtection.AuthenticatedEncryption.ConfigurationModel.AuthenticatedEncryptorDescriptorDeserializer, Microsoft.AspNetCore.DataProtection, Version=9.0.0.0, Culture=neutral, PublicKeyToken=adb9793829ddae60 to Microsoft.AspNetCore.DataProtection.AuthenticatedEncryption.ConfigurationModel.AuthenticatedEncryptorDescriptorDeserializer, Microsoft.AspNetCore.DataProtection, Culture=neutral, PublicKeyToken=adb9793829ddae60
2023-11-27T18:32:45.7350008Z [xUnit.net 00:00:57.20] | [0.003s] Microsoft.AspNetCore.DataProtection.AuthenticatedEncryption.ManagedAuthenticatedEncryptorFactory Debug: Using managed symmetric algorithm 'System.Security.Cryptography.Aes'.
2023-11-27T18:32:45.7350042Z [xUnit.net 00:00:57.20] | [0.003s] Microsoft.AspNetCore.DataProtection.AuthenticatedEncryption.ManagedAuthenticatedEncryptorFactory Debug: Using managed keyed hash algorithm 'System.Security.Cryptography.HMACSHA256'.
2023-11-27T18:32:45.7361333Z [xUnit.net 00:00:57.20] | [0.003s] Microsoft.AspNetCore.DataProtection.KeyManagement.KeyRingProvider Debug: Using key {7182e3d8-41fd-4d14-8423-9d40fbc37f75} as the default key.
2023-11-27T18:32:45.7370306Z [xUnit.net 00:00:57.20] | [0.003s] Microsoft.AspNetCore.DataProtection.Internal.DataProtectionHostedService Debug: Key ring with default key {7182e3d8-41fd-4d14-8423-9d40fbc37f75} was loaded during application startup.
2023-11-27T18:32:45.7381524Z [xUnit.net 00:00:57.20] | [0.003s] Microsoft.AspNetCore.SignalR.Internal.DefaultHubProtocolResolver Debug: Registered SignalR Protocol: json, implemented by Microsoft.AspNetCore.SignalR.Protocol.JsonHubProtocol.
2023-11-27T18:32:45.7392960Z [xUnit.net 00:00:57.20] | [0.004s] Microsoft.AspNetCore.SignalR.Internal.DefaultHubProtocolResolver Debug: Registered SignalR Protocol: messagepack, implemented by Microsoft.AspNetCore.SignalR.Protocol.MessagePackHubProtocol.
2023-11-27T18:32:45.7771049Z [xUnit.net 00:00:57.20] | [0.022s] Microsoft.Hosting.Lifetime Information: Now listening on: http://127.0.0.1:33165
2023-11-27T18:32:45.7831652Z [xUnit.net 00:00:57.20] | [0.022s] Microsoft.AspNetCore.Hosting.Diagnostics Debug: Loaded hosting startup assembly Microsoft.AspNetCore.SignalR.Client.FunctionalTests
2023-11-27T18:32:45.7831766Z [xUnit.net 00:00:57.20] | [0.022s] Microsoft.Hosting.Lifetime Information: Application started. Press Ctrl+C to shut down.
2023-11-27T18:32:45.7831802Z [xUnit.net 00:00:57.20] | [0.022s] Microsoft.Hosting.Lifetime Information: Hosting environment: Production
2023-11-27T18:32:45.7831831Z [xUnit.net 00:00:57.20] | [0.022s] Microsoft.Hosting.Lifetime Information: Content root path: /datadisks/disk1/work/BD8109E1/w/B561098B/e
2023-11-27T18:32:45.7831861Z [xUnit.net 00:00:57.20] | [0.022s] Microsoft.Extensions.Hosting.Internal.Host Debug: Hosting started
2023-11-27T18:32:45.7831894Z [xUnit.net 00:00:57.20] | [0.022s] Microsoft.AspNetCore.SignalR.Tests.InProcessTestServer Information: Test Server started
2023-11-27T18:32:45.7836471Z [xUnit.net 00:00:57.20] | [0.023s] Microsoft.AspNetCore.SignalR.Client.HubConnection Debug: Starting HubConnection.
2023-11-27T18:32:45.7841884Z [xUnit.net 00:00:57.21] | [0.023s] Microsoft.AspNetCore.Http.Connections.Client.HttpConnection Debug: Starting HttpConnection.
2023-11-27T18:32:45.7853265Z [xUnit.net 00:00:57.21] | [0.023s] Microsoft.AspNetCore.Http.Connections.Client.HttpConnection Debug: Establishing connection with server at 'http://127.0.0.1:33165/default'.
2023-11-27T18:32:45.7866384Z [xUnit.net 00:00:57.21] | [0.023s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPJ" accepted.
2023-11-27T18:32:45.8432062Z [xUnit.net 00:00:57.21] | [0.023s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPJ" started.
2023-11-27T18:32:45.8441231Z [xUnit.net 00:00:57.21] | [0.023s] Microsoft.AspNetCore.Hosting.Diagnostics Information: Request starting HTTP/1.1 POST http://127.0.0.1:33165/default/negotiate?negotiateVersion=1&useStatefulReconnect=true - - 0
2023-11-27T18:32:45.8451330Z [xUnit.net 00:00:57.21] | [0.024s] Microsoft.AspNetCore.Routing.Matching.DfaMatcher Debug: 1 candidate(s) found for the request path '/default/negotiate'
2023-11-27T18:32:45.8518366Z [xUnit.net 00:00:57.21] | [0.024s] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware Debug: Request matched endpoint '/default/negotiate'
2023-11-27T18:32:45.8518434Z [xUnit.net 00:00:57.21] | [0.024s] Microsoft.AspNetCore.Authentication.JwtBearer.JwtBearerHandler Debug: AuthenticationScheme: Bearer was not authenticated.
2023-11-27T18:32:45.8518463Z [xUnit.net 00:00:57.21] | [0.024s] Microsoft.AspNetCore.Routing.EndpointMiddleware Information: Executing endpoint '/default/negotiate'
2023-11-27T18:32:45.8518496Z [xUnit.net 00:00:57.21] | [0.024s] Microsoft.AspNetCore.Http.Connections.Internal.HttpConnectionManager Debug: New connection nV2KQoE1d7SsYFtDC_6feQ created.
2023-11-27T18:32:45.8518528Z [xUnit.net 00:00:57.21] | [0.024s] Microsoft.AspNetCore.Http.Connections.Internal.HttpConnectionDispatcher Debug: nV2KQoE1d7SsYFtDC_6feQ - Sending negotiation response.
2023-11-27T18:32:45.8518561Z [xUnit.net 00:00:57.21] | [0.024s] Microsoft.AspNetCore.Routing.EndpointMiddleware Information: Executed endpoint '/default/negotiate'
2023-11-27T18:32:45.8518646Z [xUnit.net 00:00:57.21] | [0.024s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPJ" completed keep alive response.
2023-11-27T18:32:45.8518742Z [xUnit.net 00:00:57.21] | [0.025s] Microsoft.AspNetCore.Hosting.Diagnostics Information: Request finished HTTP/1.1 POST http://127.0.0.1:33165/default/negotiate?negotiateVersion=1&useStatefulReconnect=true - 200 344 application/json 1.2709ms
2023-11-27T18:32:45.8525888Z [xUnit.net 00:00:57.21] | [0.025s] Microsoft.AspNetCore.Http.Connections.Client.HttpConnection Debug: Established connection 'nV2KQoE1d7SsYFtDC_6feQ' with the server.
2023-11-27T18:32:45.8536352Z [xUnit.net 00:00:57.21] | [0.025s] Microsoft.AspNetCore.Http.Connections.Client.HttpConnection Debug: Starting transport 'WebSockets' with Url: http://127.0.0.1:33165/default.
2023-11-27T18:32:45.8962574Z [xUnit.net 00:00:57.21] | [0.025s] Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport Information: Starting transport. Transfer mode: Text. Url: 'ws://127.0.0.1:33165/default?id=Id7H2ys0JE7Oz0oVooVktg'.
2023-11-27T18:32:45.8968822Z [xUnit.net 00:00:57.21] | [0.026s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPK" accepted.
2023-11-27T18:32:45.9031963Z [xUnit.net 00:00:57.21] | [0.026s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPK" started.
2023-11-27T18:32:45.9032057Z [xUnit.net 00:00:57.21] | [0.026s] Microsoft.AspNetCore.Hosting.Diagnostics Information: Request starting HTTP/1.1 GET http://127.0.0.1:33165/generateJwtToken/test1 - - -
2023-11-27T18:32:45.9032287Z [xUnit.net 00:00:57.21] | [0.027s] Microsoft.AspNetCore.Routing.Matching.DfaMatcher Debug: 1 candidate(s) found for the request path '/generateJwtToken/test1'
2023-11-27T18:32:45.9037193Z [xUnit.net 00:00:57.21] | [0.027s] Microsoft.AspNetCore.Routing.Matching.DfaMatcher Debug: Endpoint 'HTTP: GET /generateJwtToken/{name?}' with route pattern '/generateJwtToken/{name?}' is valid for the request path '/generateJwtToken/test1'
2023-11-27T18:32:45.9037830Z [xUnit.net 00:00:57.21] | [0.027s] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware Debug: Request matched endpoint 'HTTP: GET /generateJwtToken/{name?}'
2023-11-27T18:32:45.9037999Z [xUnit.net 00:00:57.21] | [0.027s] Microsoft.AspNetCore.Authentication.JwtBearer.JwtBearerHandler Debug: AuthenticationScheme: Bearer was not authenticated.
2023-11-27T18:32:45.9038031Z [xUnit.net 00:00:57.21] | [0.027s] Microsoft.AspNetCore.Routing.EndpointMiddleware Information: Executing endpoint 'HTTP: GET /generateJwtToken/{name?}'
2023-11-27T18:32:45.9048664Z [xUnit.net 00:00:57.21] | [0.028s] Microsoft.AspNetCore.Routing.EndpointMiddleware Information: Executed endpoint 'HTTP: GET /generateJwtToken/{name?}'
2023-11-27T18:32:45.9059365Z [xUnit.net 00:00:57.25] | [0.028s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPK" completed keep alive response.
2023-11-27T18:32:45.9059445Z [xUnit.net 00:00:57.25] | [0.028s] Microsoft.AspNetCore.Hosting.Diagnostics Information: Request finished HTTP/1.1 GET http://127.0.0.1:33165/generateJwtToken/test1 - 200 - - 1.9957ms
2023-11-27T18:32:45.9065084Z [xUnit.net 00:00:57.25] | [0.028s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPL" accepted.
2023-11-27T18:32:45.9922036Z [xUnit.net 00:00:57.25] | [0.028s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPL" started.
2023-11-27T18:32:45.9922192Z [xUnit.net 00:00:57.25] | [0.029s] Microsoft.AspNetCore.Hosting.Diagnostics Information: Request starting HTTP/1.1 GET http://127.0.0.1:33165/default?id=Id7H2ys0JE7Oz0oVooVktg - - -
2023-11-27T18:32:45.9922221Z [xUnit.net 00:00:57.25] | [0.029s] Microsoft.AspNetCore.Routing.Matching.DfaMatcher Debug: 1 candidate(s) found for the request path '/default'
2023-11-27T18:32:45.9922249Z [xUnit.net 00:00:57.25] | [0.029s] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware Debug: Request matched endpoint '/default'
2023-11-27T18:32:45.9922275Z [xUnit.net 00:00:57.25] | [0.030s] Microsoft.AspNetCore.Authentication.JwtBearer.JwtBearerHandler Debug: Successfully validated the token.
2023-11-27T18:32:45.9922302Z [xUnit.net 00:00:57.25] | [0.030s] Microsoft.AspNetCore.Authentication.JwtBearer.JwtBearerHandler Debug: AuthenticationScheme: Bearer was successfully authenticated.
2023-11-27T18:32:45.9922410Z [xUnit.net 00:00:57.25] | [0.030s] Microsoft.AspNetCore.Routing.EndpointMiddleware Information: Executing endpoint '/default'
2023-11-27T18:32:45.9922438Z [xUnit.net 00:00:57.25] | [0.031s] Microsoft.AspNetCore.SignalR.HubConnectionHandler Debug: nV2KQoE1d7SsYFtDC_6feQ - OnConnectedAsync started.
2023-11-27T18:32:45.9922463Z [xUnit.net 00:00:57.25] | [0.031s] Microsoft.AspNetCore.Http.Connections.Internal.Transports.WebSocketsTransport Debug: nV2KQoE1d7SsYFtDC_6feQ - Socket opened using Sub-Protocol: '(null)'.
2023-11-27T18:32:45.9922490Z [xUnit.net 00:00:57.25] | [0.031s] Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport Debug: Started transport.
2023-11-27T18:32:45.9922562Z [xUnit.net 00:00:57.25] | [0.031s] Microsoft.AspNetCore.Http.Connections.Client.HttpConnection Debug: Transport 'WebSockets' started.
2023-11-27T18:32:45.9922591Z [xUnit.net 00:00:57.25] | [0.031s] Microsoft.AspNetCore.Http.Connections.Client.HttpConnection Information: HttpConnection Started.
2023-11-27T18:32:45.9922616Z [xUnit.net 00:00:57.25] | [0.031s] Microsoft.AspNetCore.SignalR.Client.HubConnection Information: Using HubProtocol 'json v2'.
2023-11-27T18:32:45.9937212Z [xUnit.net 00:00:57.25] | [0.031s] Microsoft.AspNetCore.SignalR.Client.HubConnection Debug: Sending Hub Handshake.
2023-11-27T18:32:45.9937286Z [xUnit.net 00:00:57.25] | [0.031s] Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport Debug: Received message from application. Payload size: 32.
2023-11-27T18:32:45.9937314Z [xUnit.net 00:00:57.25] | [0.031s] Microsoft.AspNetCore.SignalR.Internal.DefaultHubProtocolResolver Debug: nV2KQoE1d7SsYFtDC_6feQ - Found protocol implementation for requested protocol: json.
2023-11-27T18:32:45.9937400Z [xUnit.net 00:00:57.25] | [0.031s] Microsoft.AspNetCore.SignalR.HubConnectionContext Debug: nV2KQoE1d7SsYFtDC_6feQ - Completed connection handshake. Using HubProtocol 'json'.
2023-11-27T18:32:45.9942332Z [xUnit.net 00:00:57.25] | [0.031s] Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport Debug: Message received. Type: Text, size: 3, EndOfMessage: True.
2023-11-27T18:32:45.9948252Z [xUnit.net 00:00:57.25] | [0.031s] Microsoft.AspNetCore.SignalR.Client.HubConnection Debug: Handshake with server complete.
2023-11-27T18:32:46.1535776Z [xUnit.net 00:00:57.25] | [0.031s] Microsoft.AspNetCore.SignalR.Client.HubConnection Debug: Sending PingMessage message.
2023-11-27T18:32:46.1535914Z [xUnit.net 00:00:57.25] | [0.032s] Microsoft.AspNetCore.SignalR.Client.HubConnection Debug: Sending PingMessage message completed.
2023-11-27T18:32:46.1535943Z [xUnit.net 00:00:57.25] | [0.032s] Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport Debug: Received message from application. Payload size: 11.
2023-11-27T18:32:46.1535974Z [xUnit.net 00:00:57.25] | [0.032s] Microsoft.AspNetCore.SignalR.Client.HubConnection Debug: Receive loop starting.
2023-11-27T18:32:46.1536002Z [xUnit.net 00:00:57.25] | [0.032s] Microsoft.AspNetCore.SignalR.Client.HubConnection Information: HubConnection started.
2023-11-27T18:32:46.1536027Z [xUnit.net 00:00:57.25] | [0.034s] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets Debug: Connection id "0HMVFESFBPCPL" received FIN.
2023-11-27T18:32:46.1536111Z [xUnit.net 00:00:57.25] | [0.034s] Microsoft.AspNetCore.Http.Connections.Internal.Transports.WebSocketsTransport Debug: nV2KQoE1d7SsYFtDC_6feQ - Socket connection closed prematurely.
2023-11-27T18:32:46.1536141Z [xUnit.net 00:00:57.25] | System.Net.WebSockets.WebSocketException (0x80004005): The remote party closed the WebSocket connection without completing the close handshake.
2023-11-27T18:32:46.1536167Z [xUnit.net 00:00:57.26] | at System.Net.WebSockets.ManagedWebSocket.ThrowEOFUnexpected()
2023-11-27T18:32:46.1555733Z [xUnit.net 00:00:57.26] | at System.Net.WebSockets.ManagedWebSocket.EnsureBufferContainsAsync(Int32 minimumRequiredBytes, CancellationToken cancellationToken)
2023-11-27T18:32:46.1555817Z [xUnit.net 00:00:57.26] | at System.Runtime.CompilerServices.PoolingAsyncValueTaskMethodBuilder`1.StateMachineBox`1.System.Threading.Tasks.Sources.IValueTaskSource.GetResult(Int16 token)
2023-11-27T18:32:46.1555851Z [xUnit.net 00:00:57.26] | at System.Net.WebSockets.ManagedWebSocket.ReceiveAsyncPrivate[TResult](Memory`1 payloadBuffer, CancellationToken cancellationToken)
2023-11-27T18:32:46.1555879Z [xUnit.net 00:00:57.26] | at System.Runtime.CompilerServices.PoolingAsyncValueTaskMethodBuilder`1.StateMachineBox`1.System.Threading.Tasks.Sources.IValueTaskSource.GetResult(Int16 token)
2023-11-27T18:32:46.1555908Z [xUnit.net 00:00:57.26] | at Microsoft.AspNetCore.Http.Connections.Internal.Transports.WebSocketsServerTransport.StartReceiving(WebSocket socket) in /_/src/SignalR/common/Http.Connections/src/Internal/Transports/WebSocketsServerTransport.cs:line 140
2023-11-27T18:32:46.1556045Z [xUnit.net 00:00:57.26] | [0.034s] Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport Debug: Receive loop errored.
2023-11-27T18:32:46.1556079Z [xUnit.net 00:00:57.26] | System.Net.WebSockets.WebSocketException (0x80004005): The remote party closed the WebSocket connection without completing the close handshake.
2023-11-27T18:32:46.1556108Z [xUnit.net 00:00:57.26] | ---> System.IO.IOException: Unable to read data from the transport connection: Operation canceled.
2023-11-27T18:32:46.1556132Z [xUnit.net 00:00:57.26] | ---> System.Net.Sockets.SocketException (125): Operation canceled
2023-11-27T18:32:46.1556161Z [xUnit.net 00:00:57.26] | --- End of inner exception stack trace ---
2023-11-27T18:32:46.1556262Z [xUnit.net 00:00:57.26] | at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.System.Threading.Tasks.Sources.IValueTaskSource.GetResult(Int16 token)
2023-11-27T18:32:46.1556291Z [xUnit.net 00:00:57.26] | at System.Net.Http.HttpConnection.ReadAsync(Memory`1 destination)
2023-11-27T18:32:46.1556416Z [xUnit.net 00:00:57.26] | at System.Net.Http.HttpConnection.RawConnectionStream.ReadAsync(Memory`1 buffer, CancellationToken cancellationToken)
2023-11-27T18:32:46.1556450Z [xUnit.net 00:00:57.26] | at System.Runtime.CompilerServices.PoolingAsyncValueTaskMethodBuilder`1.StateMachineBox`1.System.Threading.Tasks.Sources.IValueTaskSource.GetResult(Int16 token)
2023-11-27T18:32:46.1556481Z [xUnit.net 00:00:57.26] | at System.Net.WebSockets.ManagedWebSocket.ReceiveAsyncPrivate[TResult](Memory`1 payloadBuffer, CancellationToken cancellationToken)
2023-11-27T18:32:46.2663327Z [xUnit.net 00:00:57.26] | at System.Net.WebSockets.ManagedWebSocket.ReceiveAsyncPrivate[TResult](Memory`1 payloadBuffer, CancellationToken cancellationToken)
2023-11-27T18:32:46.2663490Z [xUnit.net 00:00:57.26] | at System.Runtime.CompilerServices.PoolingAsyncValueTaskMethodBuilder`1.StateMachineBox`1.System.Threading.Tasks.Sources.IValueTaskSource.GetResult(Int16 token)
2023-11-27T18:32:46.2663527Z [xUnit.net 00:00:57.31] | at Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport.StartReceiving(WebSocket socket) in /_/src/SignalR/clients/csharp/Http.Connections.Client/src/Internal/WebSocketsTransport.cs:line 443
2023-11-27T18:32:46.2663584Z [xUnit.net 00:00:57.31] | [0.035s] Microsoft.AspNetCore.Http.Connections.Internal.Transports.WebSocketsTransport Debug: nV2KQoE1d7SsYFtDC_6feQ - Waiting for the application to finish sending data.
2023-11-27T18:32:46.2663616Z [xUnit.net 00:00:57.31] | [0.035s] Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport Debug: Receive loop stopped.
2023-11-27T18:32:46.2663862Z [xUnit.net 00:00:57.31] | [0.035s] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets Debug: Connection id "0HMVFESFBPCPL" sending FIN because: "The Socket transport's send loop completed gracefully."
2023-11-27T18:32:46.2663918Z [xUnit.net 00:00:57.31] | [0.035s] Microsoft.AspNetCore.Http.Connections.Internal.Transports.WebSocketsTransport Debug: nV2KQoE1d7SsYFtDC_6feQ - Socket closed.
2023-11-27T18:32:46.2663957Z [xUnit.net 00:00:57.31] | [0.035s] Microsoft.AspNetCore.Routing.EndpointMiddleware Information: Executed endpoint '/default'
2023-11-27T18:32:46.2663993Z [xUnit.net 00:00:57.31] | [0.035s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPL" disconnecting.
2023-11-27T18:32:46.2664023Z [xUnit.net 00:00:57.31] | [0.035s] Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport Debug: Send loop stopped.
2023-11-27T18:32:46.2664058Z [xUnit.net 00:00:57.31] | [0.035s] Microsoft.AspNetCore.Hosting.Diagnostics Information: Request finished HTTP/1.1 GET http://127.0.0.1:33165/default?id=Id7H2ys0JE7Oz0oVooVktg - 101 - - 6.6979ms
2023-11-27T18:32:46.2664166Z [xUnit.net 00:00:57.31] | [0.035s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPL" stopped.
2023-11-27T18:32:46.2664201Z [xUnit.net 00:00:57.31] | [0.035s] Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport Information: Starting transport. Transfer mode: Text. Url: 'ws://127.0.0.1:33165/default?id=Id7H2ys0JE7Oz0oVooVktg'.
2023-11-27T18:32:46.2664299Z [xUnit.net 00:00:57.31] | [0.036s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPM" accepted.
2023-11-27T18:32:46.2664331Z [xUnit.net 00:00:57.31] | [0.036s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPM" started.
2023-11-27T18:32:46.2664422Z [xUnit.net 00:00:57.31] | [0.036s] Microsoft.AspNetCore.Hosting.Diagnostics Information: Request starting HTTP/1.1 GET http://127.0.0.1:33165/generateJwtToken/test2 - - -
2023-11-27T18:32:46.2664455Z [xUnit.net 00:00:57.31] | [0.036s] Microsoft.AspNetCore.Routing.Matching.DfaMatcher Debug: 1 candidate(s) found for the request path '/generateJwtToken/test2'
2023-11-27T18:32:46.3295970Z [xUnit.net 00:00:57.31] | [0.036s] Microsoft.AspNetCore.Routing.Matching.DfaMatcher Debug: Endpoint 'HTTP: GET /generateJwtToken/{name?}' with route pattern '/generateJwtToken/{name?}' is valid for the request path '/generateJwtToken/test2'
2023-11-27T18:32:46.3296143Z [xUnit.net 00:00:57.31] | [0.036s] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware Debug: Request matched endpoint 'HTTP: GET /generateJwtToken/{name?}'
2023-11-27T18:32:46.3296182Z [xUnit.net 00:00:57.31] | [0.036s] Microsoft.AspNetCore.Authentication.JwtBearer.JwtBearerHandler Debug: AuthenticationScheme: Bearer was not authenticated.
2023-11-27T18:32:46.3296215Z [xUnit.net 00:00:57.31] | [0.036s] Microsoft.AspNetCore.Routing.EndpointMiddleware Information: Executing endpoint 'HTTP: GET /generateJwtToken/{name?}'
2023-11-27T18:32:46.3296301Z [xUnit.net 00:00:57.31] | [0.036s] Microsoft.AspNetCore.Routing.EndpointMiddleware Information: Executed endpoint 'HTTP: GET /generateJwtToken/{name?}'
2023-11-27T18:32:46.3296335Z [xUnit.net 00:00:57.31] | [0.036s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPM" completed keep alive response.
2023-11-27T18:32:46.3296968Z [xUnit.net 00:00:57.31] | [0.036s] Microsoft.AspNetCore.Hosting.Diagnostics Information: Request finished HTTP/1.1 GET http://127.0.0.1:33165/generateJwtToken/test2 - 200 - - 0.3546ms
2023-11-27T18:32:46.3302839Z [xUnit.net 00:00:57.31] | [0.036s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPN" accepted.
2023-11-27T18:32:46.3303501Z [xUnit.net 00:00:57.31] | [0.036s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPN" started.
2023-11-27T18:32:46.3315148Z [xUnit.net 00:00:57.31] | [0.036s] Microsoft.AspNetCore.Hosting.Diagnostics Information: Request starting HTTP/1.1 GET http://127.0.0.1:33165/default?id=Id7H2ys0JE7Oz0oVooVktg - - -
2023-11-27T18:32:46.3315760Z [xUnit.net 00:00:57.31] | [0.037s] Microsoft.AspNetCore.Routing.Matching.DfaMatcher Debug: 1 candidate(s) found for the request path '/default'
2023-11-27T18:32:46.3316292Z [xUnit.net 00:00:57.31] | [0.037s] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware Debug: Request matched endpoint '/default'
2023-11-27T18:32:46.3322767Z [xUnit.net 00:00:57.31] | [0.037s] Microsoft.AspNetCore.Authentication.JwtBearer.JwtBearerHandler Debug: Successfully validated the token.
2023-11-27T18:32:46.3323125Z [xUnit.net 00:00:57.31] | [0.037s] Microsoft.AspNetCore.Authentication.JwtBearer.JwtBearerHandler Debug: AuthenticationScheme: Bearer was successfully authenticated.
2023-11-27T18:32:46.3330515Z [xUnit.net 00:00:57.31] | [0.037s] Microsoft.AspNetCore.Routing.EndpointMiddleware Information: Executing endpoint '/default'
2023-11-27T18:32:46.3330769Z [xUnit.net 00:00:57.31] | [0.037s] Microsoft.AspNetCore.Http.Connections.Internal.HttpConnectionDispatcher Warning: nV2KQoE1d7SsYFtDC_6feQ - The name of the user changed from 'test1' to 'test2'.
2023-11-27T18:32:46.3797467Z [xUnit.net 00:00:57.31] | [0.037s] Microsoft.AspNetCore.Http.Connections.Internal.Transports.WebSocketsTransport Debug: nV2KQoE1d7SsYFtDC_6feQ - Socket opened using Sub-Protocol: '(null)'.
2023-11-27T18:32:46.3797613Z [xUnit.net 00:00:57.31] | [0.037s] Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport Debug: Started transport.
2023-11-27T18:32:46.4706539Z [xUnit.net 00:00:57.31] | [0.039s] Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport Debug: Received message from application. Payload size: 26.
2023-11-27T18:32:46.4706779Z [xUnit.net 00:00:57.31] | [0.039s] Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport Debug: Message received. Type: Text, size: 26, EndOfMessage: True.
2023-11-27T18:32:46.4706814Z [xUnit.net 00:00:57.31] | [0.039s] Microsoft.AspNetCore.SignalR.Client.HubConnection Debug: Processing 26 byte message from server.
2023-11-27T18:32:46.4706848Z [xUnit.net 00:00:57.31] | [0.040s] Microsoft.AspNetCore.SignalR.Client.HubConnection Debug: Sending CloseMessage message.
2023-11-27T18:32:46.4706878Z [xUnit.net 00:00:57.31] | [0.040s] Microsoft.AspNetCore.SignalR.Client.HubConnection Debug: Sending CloseMessage message completed.
2023-11-27T18:32:46.4706908Z [xUnit.net 00:00:57.31] | [0.040s] Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport Debug: Received message from application. Payload size: 11.
2023-11-27T18:32:46.4707031Z [xUnit.net 00:00:57.31] | [0.040s] Microsoft.AspNetCore.SignalR.Client.HubConnection Debug: Stopping HubConnection.
2023-11-27T18:32:46.4707064Z [xUnit.net 00:00:57.37] | [0.040s] Microsoft.AspNetCore.SignalR.Client.HubConnection Debug: Terminating receive loop.
2023-11-27T18:32:46.4707196Z [xUnit.net 00:00:57.37] | [0.040s] Microsoft.AspNetCore.SignalR.Client.HubConnection Debug: Waiting for the receive loop to terminate.
2023-11-27T18:32:46.4707231Z [xUnit.net 00:00:57.37] | [0.040s] Microsoft.AspNetCore.Http.Connections.Client.HttpConnection Debug: Disposing HttpConnection.
2023-11-27T18:32:46.4707263Z [xUnit.net 00:00:57.37] | [0.040s] Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport Information: Transport is stopping.
2023-11-27T18:32:46.4707295Z [xUnit.net 00:00:57.37] | [0.040s] Microsoft.AspNetCore.SignalR.HubConnectionHandler Debug: nV2KQoE1d7SsYFtDC_6feQ - OnConnectedAsync ending.
2023-11-27T18:32:46.4707329Z [xUnit.net 00:00:57.37] | [0.040s] Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport Debug: Send loop stopped.
2023-11-27T18:32:46.4707442Z [xUnit.net 00:00:57.37] | [0.040s] Microsoft.AspNetCore.Http.Connections.Internal.Transports.WebSocketsTransport Debug: nV2KQoE1d7SsYFtDC_6feQ - Waiting for the client to close the socket.
2023-11-27T18:32:46.4707478Z [xUnit.net 00:00:57.37] | [0.040s] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets Debug: Connection id "0HMVFESFBPCPN" received FIN.
2023-11-27T18:32:46.4707513Z [xUnit.net 00:00:57.37] | [0.040s] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets Debug: Connection id "0HMVFESFBPCPN" reset.
2023-11-27T18:32:46.4707543Z [xUnit.net 00:00:57.37] | [0.040s] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets Debug: Connection id "0HMVFESFBPCPN" sending RST because: "Broken pipe"
2023-11-27T18:32:46.4707575Z [xUnit.net 00:00:57.37] | [0.041s] Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport Debug: Receive loop canceled.
2023-11-27T18:32:46.4707608Z [xUnit.net 00:00:57.37] | [0.041s] Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport Debug: Receive loop stopped.
2023-11-27T18:32:46.4707639Z [xUnit.net 00:00:57.37] | [0.041s] Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport Debug: Transport stopped.
2023-11-27T18:32:46.4707777Z [xUnit.net 00:00:57.37] | [0.041s] Microsoft.AspNetCore.Http.Connections.Client.HttpConnection Information: HttpConnection Disposed.
2023-11-27T18:32:46.4707817Z [xUnit.net 00:00:57.37] | [0.041s] Microsoft.AspNetCore.SignalR.Client.HubConnection Debug: Canceling all outstanding invocations.
2023-11-27T18:32:46.4707851Z [xUnit.net 00:00:57.37] | [0.041s] Microsoft.AspNetCore.SignalR.Client.HubConnection Debug: HubConnection stopped.
2023-11-27T18:32:46.5243796Z [xUnit.net 00:00:57.37] | [0.041s] Microsoft.AspNetCore.SignalR.Tests.InProcessTestServer Information: Start shutting down test server
2023-11-27T18:32:46.5244005Z [xUnit.net 00:00:57.37] | [0.041s] Microsoft.AspNetCore.Http.Connections.Internal.Transports.WebSocketsTransport Debug: nV2KQoE1d7SsYFtDC_6feQ - Socket connection closed prematurely.
2023-11-27T18:32:46.5244033Z [xUnit.net 00:00:57.37] | System.Net.WebSockets.WebSocketException (0x80004005): The remote party closed the WebSocket connection without completing the close handshake.
2023-11-27T18:32:46.5244061Z [xUnit.net 00:00:57.37] | ---> Microsoft.AspNetCore.Connections.ConnectionResetException: Broken pipe
2023-11-27T18:32:46.5244087Z [xUnit.net 00:00:57.37] | ---> System.Net.Sockets.SocketException (32): Broken pipe
2023-11-27T18:32:46.5244112Z [xUnit.net 00:00:57.37] | --- End of inner exception stack trace ---
2023-11-27T18:32:46.5244136Z [xUnit.net 00:00:57.37] | at System.IO.Pipelines.Pipe.GetReadAsyncResult()
2023-11-27T18:32:46.5244318Z [xUnit.net 00:00:57.37] | at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.Http1UpgradeMessageBody.ReadAsyncInternalAwaited(ValueTask`1 readTask, CancellationToken cancellationToken)
2023-11-27T18:32:46.5244346Z [xUnit.net 00:00:57.37] | at System.Runtime.CompilerServices.PoolingAsyncValueTaskMethodBuilder`1.StateMachineBox`1.System.Threading.Tasks.Sources.IValueTaskSource.GetResult(Int16 token)
2023-11-27T18:32:46.5244371Z [xUnit.net 00:00:57.37] | at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.HttpRequestStream.ReadAsyncInternal(Memory`1 destination, CancellationToken cancellationToken) in /_/src/Servers/Kestrel/Core/src/Internal/Http/HttpRequestStream.cs:line 118
2023-11-27T18:32:46.5244416Z [xUnit.net 00:00:57.37] | at System.Runtime.CompilerServices.PoolingAsyncValueTaskMethodBuilder`1.StateMachineBox`1.System.Threading.Tasks.Sources.IValueTaskSource.GetResult(Int16 token)
2023-11-27T18:32:46.5244441Z [xUnit.net 00:00:57.37] | at System.Net.WebSockets.ManagedWebSocket.ReceiveAsyncPrivate[TResult](Memory`1 payloadBuffer, CancellationToken cancellationToken)
2023-11-27T18:32:46.5244534Z [xUnit.net 00:00:57.37] | at System.Net.WebSockets.ManagedWebSocket.ReceiveAsyncPrivate[TResult](Memory`1 payloadBuffer, CancellationToken cancellationToken)
2023-11-27T18:32:46.5244559Z [xUnit.net 00:00:57.37] | at System.Runtime.CompilerServices.PoolingAsyncValueTaskMethodBuilder`1.StateMachineBox`1.System.Threading.Tasks.Sources.IValueTaskSource.GetResult(Int16 token)
2023-11-27T18:32:46.5244618Z [xUnit.net 00:00:57.37] | at Microsoft.AspNetCore.Http.Connections.Internal.Transports.WebSocketsServerTransport.StartReceiving(WebSocket socket) in /_/src/SignalR/common/Http.Connections/src/Internal/Transports/WebSocketsServerTransport.cs:line 140
2023-11-27T18:32:46.5244664Z [xUnit.net 00:00:57.37] | [0.041s] Microsoft.Extensions.Hosting.Internal.Host Debug: Hosting stopping
2023-11-27T18:32:46.5244689Z [xUnit.net 00:00:57.37] | [0.041s] Microsoft.AspNetCore.Http.Connections.Internal.Transports.WebSocketsTransport Debug: nV2KQoE1d7SsYFtDC_6feQ - Socket closed.
2023-11-27T18:32:46.5244713Z [xUnit.net 00:00:57.37] | [0.042s] Microsoft.AspNetCore.Http.Connections.Internal.HttpConnectionManager Debug: nV2KQoE1d7SsYFtDC_6feQ - Removing connection Id7H2ys0JE7Oz0oVooVktg from the list of connections.
2023-11-27T18:32:46.5244763Z [xUnit.net 00:00:57.37] | [0.042s] Microsoft.AspNetCore.Routing.EndpointMiddleware Information: Executed endpoint '/default'
2023-11-27T18:32:46.5598049Z [xUnit.net 00:00:57.37] | [0.042s] Microsoft.AspNetCore.Hosting.Diagnostics Information: Request finished HTTP/1.1 GET http://127.0.0.1:33165/default?id=Id7H2ys0JE7Oz0oVooVktg - 101 - - 5.1190ms
2023-11-27T18:32:46.5598181Z [xUnit.net 00:00:57.37] | [0.042s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPN" disconnecting.
2023-11-27T18:32:46.5598208Z [xUnit.net 00:00:57.37] | [0.042s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPN" stopped.
2023-11-27T18:32:46.5598293Z [xUnit.net 00:00:57.37] | [0.042s] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets Debug: Connection id "0HMVFESFBPCPJ" received FIN.
2023-11-27T18:32:46.5598319Z [xUnit.net 00:00:57.42] | [0.042s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPJ" disconnecting.
2023-11-27T18:32:46.5598347Z [xUnit.net 00:00:57.42] | [0.042s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPJ" stopped.
2023-11-27T18:32:46.5598529Z [xUnit.net 00:00:57.42] | [0.042s] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets Debug: Connection id "0HMVFESFBPCPJ" sending FIN because: "The Socket transport's send loop completed gracefully."
2023-11-27T18:32:46.5598560Z [xUnit.net 00:00:57.43] | [0.045s] Microsoft.Hosting.Lifetime Information: Application is shutting down...
2023-11-27T18:32:46.5598585Z [xUnit.net 00:00:57.43] | [0.046s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPK" disconnecting.
2023-11-27T18:32:46.5603971Z [xUnit.net 00:00:57.43] | [0.046s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPK" stopped.
2023-11-27T18:32:46.5608604Z [xUnit.net 00:00:57.43] | [0.046s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPM" disconnecting.
2023-11-27T18:32:46.5613258Z [xUnit.net 00:00:57.43] | [0.046s] Microsoft.AspNetCore.Server.Kestrel.Connections Debug: Connection id "0HMVFESFBPCPM" stopped.
2023-11-27T18:32:46.5716530Z [xUnit.net 00:00:57.43] | [0.046s] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets Debug: Connection id "0HMVFESFBPCPK" sending FIN because: "The Socket transport's send loop completed gracefully."
2023-11-27T18:32:46.5722067Z [xUnit.net 00:00:57.43] | [0.046s] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets Debug: Connection id "0HMVFESFBPCPM" sending FIN because: "The Socket transport's send loop completed gracefully."
2023-11-27T18:32:46.5726748Z [xUnit.net 00:00:57.43] | [0.046s] Microsoft.AspNetCore.SignalR.Tests.InProcessTestServer Information: Test server shut down
2023-11-27T18:32:46.5731457Z [xUnit.net 00:00:57.43] | [0.046s] Microsoft.Extensions.Hosting.Internal.Host Debug: Hosting stopped
2023-11-27T18:32:46.5780920Z [xUnit.net 00:00:57.43] | [0.051s] TestLifetime Information: Finished test ChangingUserNameDuringReconnectLogsWarning in 0.0502106s
```

## Build

https://dev.azure.com/dnceng-public/public/_build/results?buildId=481329

Contributor guide

Open the contributing guide

Assessment

This issue has not been assessed yet.

Get new issues in your inbox

A short digest of beginner-friendly GitHub issues.