Timeout in SocketsHttpHandler_HttpClientHandlerTest.GetAsync_ManyDifferentResponseHeaders_ParsedCorrectly

[steveharter]

Build Information

Build: https://dev.azure.com/dnceng-public/cbb18261-c48f-4abb-8651-8cdcb5474649/_build/results?buildId=951717
Build error leg or test failing: System.Net.Http.Functional.Tests.SocketsHttpHandler_HttpClientHandlerTest.GetAsync_ManyDifferentResponseHeaders_ParsedCorrectly
Pull request: #112534

Error Message

Fill the error message using step by step known issues guidance.

{
  "ErrorMessage": ["[FAIL] System.Net.Http.Functional.Tests.SocketsHttpHandler_HttpClientHandlerTest.GetAsync_ManyDifferentResponseHeaders_ParsedCorrectly", "System.TimeoutException"],
  "ErrorPattern": "",
  "BuildRetry": true,
  "ExcludeConsoleLog": false
}

log

[21:31:53] info: Using threadless Xunit runner
[21:31:53] info: Discovering: System.Net.Http.Functional.Tests.dll (method display = ClassAndMethod, method display options = None)
[21:31:56] info: Discovered:  System.Net.Http.Functional.Tests.dll (found 460 of 1877 test cases)
[21:31:56] info: Starting:    System.Net.Http.Functional.Tests.dll
[21:32:00] fail: [out of order message from the browser]: http://127.0.0.1:59463/ - Failed to load resource: net::ERR_CONTENT_LENGTH_MISMATCH
[21:32:00] fail: [out of order message from the browser]: http://127.0.0.1:62303/ - Failed to load resource: net::ERR_CONTENT_LENGTH_MISMATCH
[21:32:01] fail: [out of order message from the browser]: http://127.0.0.1:59671/ - Failed to load resource: net::ERR_INVALID_CHUNKED_ENCODING
[21:32:01] fail: [out of order message from the browser]: http://127.0.0.1:49491/ - Failed to load resource: net::ERR_INVALID_CHUNKED_ENCODING
[21:32:01] fail: [out of order message from the browser]: http://127.0.0.1:64746/ - Failed to load resource: net::ERR_INVALID_CHUNKED_ENCODING
[21:32:01] fail: [out of order message from the browser]: http://127.0.0.1:56904/ - Failed to load resource: net::ERR_INVALID_CHUNKED_ENCODING
[21:32:01] fail: [out of order message from the browser]: http://127.0.0.1:52814/ - Failed to load resource: net::ERR_INVALID_CHUNKED_ENCODING
[21:32:01] fail: [out of order message from the browser]: http://127.0.0.1:62012/ - Failed to load resource: net::ERR_INVALID_CHUNKED_ENCODING
[21:33:45] info: [FAIL] System.Net.Http.Functional.Tests.SocketsHttpHandler_HttpClientHandlerTest.GetAsync_ManyDifferentResponseHeaders_ParsedCorrectly(newline: "\r\n", fold: "\r\n ", dribble: False)
[21:33:45] info: System.Threading.Tasks.TaskCanceledException : The request was canceled due to the configured HttpClient.Timeout of 100 seconds elapsing.
[21:33:45] info: ---- System.TimeoutException : The operation was canceled.
[21:33:45] info: -------- System.Threading.Tasks.TaskCanceledException : The operation was canceled.
[21:33:45] info: ------------ System.Runtime.InteropServices.JavaScript.JSException : Error: OperationCanceledException
[21:33:45] info:    at System.Net.Http.HttpClient.HandleFailure(Exception e, Boolean telemetryStarted, HttpResponseMessage response, CancellationTokenSource cts, CancellationToken cancellationToken, CancellationTokenSource pendingRequestsCts)
[21:33:45] info:    at System.Net.Http.HttpClient.<SendAsync>g__Core|83_0(HttpRequestMessage request, HttpCompletionOption completionOption, CancellationTokenSource cts, Boolean disposeCts, CancellationTokenSource pendingRequestsCts, CancellationToken originalCancellationToken)
[21:33:45] info:    at System.Net.Http.Functional.Tests.HttpClientHandlerTest.<>c__DisplayClass21_0.<<GetAsync_ManyDifferentResponseHeaders_ParsedCorrectly>b__0>d.MoveNext()
[21:33:45] info: --- End of stack trace from previous location ---
[21:33:45] info:    at System.Threading.Tasks.TaskTimeoutExtensions.GetRealException(Task task)
[21:33:45] info: --- End of stack trace from previous location ---
[21:33:45] info:    at System.Threading.Tasks.TaskTimeoutExtensions.WhenAllOrAnyFailed(Task[] tasks)
[21:33:45] info:    at System.Net.Test.Common.LoopbackServer.<>c__DisplayClass15_0.<<CreateClientAndServerAsync>b__0>d.MoveNext()
[21:33:45] info: --- End of stack trace from previous location ---
[21:33:45] info:    at System.Net.Test.Common.LoopbackServer.CreateServerAsync(Func`2 funcAsync, Options options)
[21:33:45] info:    at System.Net.Http.Functional.Tests.HttpClientHandlerTest.GetAsync_ManyDifferentResponseHeaders_ParsedCorrectly(String newline, String fold, Boolean dribble)
[21:33:45] info: --- End of stack trace from previous location ---
[21:33:45] info: ----- Inner Stack Trace -----
[21:33:45] info: 
[21:33:45] info: ----- Inner Stack Trace -----
[21:33:45] info:    at System.Net.Http.CancellationHelper.ThrowOperationCanceledException(Exception innerException, CancellationToken cancellationToken)
[21:33:45] info:    at System.Net.Http.CancellationHelper.ThrowIfCancellationRequested(Exception innerException, CancellationToken cancellationToken)
[21:33:45] info:    at System.Net.Http.BrowserHttpInterop.CancellationHelper(Task promise, CancellationToken cancellationToken, JSObject jsController)
[21:33:45] info:    at System.Net.Http.BrowserHttpController.CallFetch()
[21:33:45] info:    at System.Net.Http.HttpClient.<SendAsync>g__Core|83_0(HttpRequestMessage request, HttpCompletionOption completionOption, CancellationTokenSource cts, Boolean disposeCts, CancellationTokenSource pendingRequestsCts, CancellationToken originalCancellationToken)
[21:33:45] info: ----- Inner Stack Trace -----
[21:33:45] info:    at System.Net.Http.BrowserHttpInterop.CancellationHelper(Task promise, CancellationToken cancellationToken, JSObject jsController)
[21:33:45] info:     at Xr.cancel (http://127.0.0.1:64750/_framework/dotnet.runtime.js:3:43267)
[21:33:45] info:     at http://127.0.0.1:64750/_framework/dotnet.runtime.js:3:174484
[21:33:45] info:     at http://127.0.0.1:64750/_framework/dotnet.runtime.js:3:174493
[21:33:45] info:     at _r (http://127.0.0.1:64750/_framework/dotnet.runtime.js:3:35045)
[21:33:45] info:     at Fc (http://127.0.0.1:64750/_framework/dotnet.runtime.js:3:174271)
[21:33:45] info:     at http://127.0.0.1:64750/_framework/dotnet.native.wasm:wasm-function[279]:0x1f9f9
[21:33:45] info:     at http://127.0.0.1:64750/_framework/dotnet.native.wasm:wasm-function[210]:0x1cef3
[21:33:45] info:     at http://127.0.0.1:64750/_framework/dotnet.native.wasm:wasm-function[201]:0xedb9
[21:33:45] info:     at http://127.0.0.1:64750/_framework/dotnet.native.wasm:wasm-function[272]:0x1f49e
[21:33:45] info:     at http://127.0.0.1:64750/_framework/dotnet.native.wasm:wasm-function[308]:0x20644
[21:33:45] info:     at http://127.0.0.1:64750/_framework/dotnet.native.wasm:wasm-function[180]:0xca07
[21:33:45] info:     at http://127.0.0.1:64750/_framework/dotnet.native.wasm:wasm-function[3547]:0x1130f4
[21:33:45] info:     at mono_wasm_schedule_timer_tick (http://127.0.0.1:64750/_framework/dotnet.runtime.js:3:56201)
[21:33:45] info:     at callUserCallback (http://127.0.0.1:64750/_framework/dotnet.native.js:8:106483)
[21:33:45] info:     at http://127.0.0.1:64750/_framework/dotnet.native.js:8:106751
[21:33:46] fail: [out of order message from the browser]: http://127.0.0.1:53733/ - Failed to load resource: net::ERR_INVALID_CHUNKED_ENCODING
[21:33:46] fail: [out of order message from the browser]: http://127.0.0.1:51983/ - Failed to load resource: net::ERR_INVALID_CHUNKED_ENCODING
[21:33:46] fail: [out of order message from the browser]: https://127.0.0.1:64751/StatusCode.ashx?statuscode=500&statusdescription=test&delay=100 - Failed to load resource: the server responded with a status of 500 ()
[21:33:46] fail: [out of order message from the browser]: https://127.0.0.1:64751/Echo.ashx - Failed to load resource: net::ERR_FAILED
[21:33:46] fail: [out of order message from the browser]: https://127.0.0.1:64751/Echo.ashx - Failed to load resource: net::ERR_FAILED
[21:33:46] fail: [out of order message from the browser]: https://127.0.0.1:64751/Echo.ashx - Failed to load resource: net::ERR_FAILED
[21:33:46] fail: [out of order message from the browser]: https://127.0.0.1:64751/Echo.ashx - Failed to load resource: net::ERR_FAILED
[21:33:46] fail: [out of order message from the browser]: https://127.0.0.1:64751/Echo.ashx - Failed to load resource: net::ERR_FAILED
[21:33:46] fail: [out of order message from the browser]: https://127.0.0.1:64751/Echo.ashx - Failed to load resource: net::ERR_FAILED
[21:34:01] fail: [out of order message from the browser]: http://127.0.0.1:65197/ - Failed to load resource: net::ERR_CONTENT_LENGTH_MISMATCH
[21:34:01] fail: [out of order message from the browser]: http://127.0.0.1:59774/ - Failed to load resource: net::ERR_INCOMPLETE_CHUNKED_ENCODING
[21:34:01] fail: [out of order message from the browser]: http://127.0.0.1:63692/ - Failed to load resource: net::ERR_INCOMPLETE_CHUNKED_ENCODING
[21:34:02] fail: [out of order message from the browser]: https://127.0.0.1:64751/Echo.ashx - Failed to load resource: net::ERR_FAILED
[21:34:02] fail: [out of order message from the browser]: https://127.0.0.1:64751/Echo.ashx - Failed to load resource: net::ERR_FAILED
[21:34:04] info: Finished:    System.Net.Http.Functional.Tests.dll
[21:34:05] info: Received expected 338383 of C:\helix\work\workitem\uploads\xharness-output\testResults.xml
[21:34:05] info: Finished writing 338383 bytes of RESULTXML
[21:34:05] info: Xml file was written to the provided writer.
[21:34:05] info: 
[21:34:05] info: === TEST EXECUTION SUMMARY ===
[21:34:05] info: Tests run: 765 Passed: 667 Inconclusive: 0 Failed: 1 Ignored: 0 Skipped: 97

Known issue validation

Build: 🔎 https://dev.azure.com/dnceng-public/public/_build/results?buildId=951717
Error message validated: [[FAIL] System.Net.Http.Functional.Tests.SocketsHttpHandler_HttpClientHandlerTest.GetAsync_ManyDifferentResponseHeaders_ParsedCorrectly System.TimeoutException]
Result validation: ✅ Known issue matched with the provided build.
Validation performed at: 2/20/2025 5:53:07 PM UTC

Report

Build	Definition	Test	Pull Request
965210	dotnet/runtime	WasmTestOnChrome-ST-System.Net.Http.Functional.Tests.WorkItemExecution	#113003
963494	dotnet/runtime	WasmTestOnChrome-ST-System.Net.Http.Functional.Tests.WorkItemExecution	#112815
951717	dotnet/runtime	WasmTestOnChrome-ST-System.Net.Http.Functional.Tests.WorkItemExecution	#112534

Summary

24-Hour Hit Count	7-Day Hit Count	1-Month Count
0	2	3

[dotnet-policy-service]

Tagging subscribers to this area: @dotnet/ncl
See info in area-owners.md if you want to be subscribed.

[dotnet-policy-service]

Tagging subscribers to 'arch-wasm': @lewing
See info in area-owners.md if you want to be subscribed.

[antonfirsov]

The test is timing out on WASM.