Repository navigation
CI failure in System.Net.Http.Functional.Tests - Operation timeout #75611
Copy link
Copy link
Closed
Labels
area-System.Net.Httptest-failuretest-run-coreTest failures in .NET Core test runsTest failures in .NET Core test runs
Milestone
Description
Seen in this release/7.0 backport PR: #75373
Please help determine if this needs a fix backported to 7.0.
BTW - I found two issues that looked similar, but they are not quite the same, which is why I opened this one. The issues are:
- System.Net.Http.Functional - HTTP/3 timeouts (mostly on NativeAOT) #75493
- System.Net.Http.Functional.Tests long running tests #68289
Details
Queue: Libraries Test Run release coreclr Linux x64 Debug
Job results: https://dev.azure.com/dnceng-public/public/_build/results?buildId=13080&view=logs&j=b827109d-f627-5869-7deb-edacb438680e&t=85e93d53-1ed9-516b-1635-3169c07ac90d&l=104
Log file: https://helixre107v0xdeko0k025g8.blob.core.windows.net/dotnet-runtime-refs-pull-75373-merge-9a35e1b62af84c0492/System.Net.Http.Functional.Tests.Attempt.3/3/console.b9bce8f9.log?helixlogtype=result
Since logs get deleted, here is an output example:
Callstack
System.Net.Http.Functional.Tests.SocketsHttpHandlerTest_Cookies.GetAsync_AddMultipleCookieHeaders_CookiesSent [FAIL]
System.TimeoutException : The operation has timed out.
Stack Trace:
/_/src/libraries/Common/tests/System/Net/Http/HttpClientHandlerTest.Cookies.cs(154,0): at System.Net.Http.Functional.Tests.HttpClientHandlerTest_Cookies.GetAsync_AddMultipleCookieHeaders_CookiesSent()
--- End of stack trace from previous location ---
System.Net.Http.Functional.Tests.Http2ResponseStreamZeroByteReadTest.ZeroByteRead_BlocksUntilDataIsAvailable(async: True) [FAIL]
System.TimeoutException : The operation has timed out.
Stack Trace:
/_/src/libraries/System.Net.Http/tests/FunctionalTests/ResponseStreamZeroByteReadTests.cs(229,0): at System.Net.Http.Functional.Tests.ResponseStreamZeroByteReadTestBase.ZeroByteRead_BlocksUntilDataIsAvailable(Boolean async)
--- End of stack trace from previous location ---
System.Net.Http.Functional.Tests.SocketsHttpHandlerTest_PlaintextStreamFilter_Http2.PlaintextStreamFilter_SimpleDelegatingStream_Success(useSsl: True) [FAIL]
System.TimeoutException : The operation has timed out.
Stack Trace:
/_/src/libraries/System.Net.Http/tests/FunctionalTests/SocketsHttpHandlerTest.cs(3203,0): at System.Net.Http.Functional.Tests.SocketsHttpHandlerTest_PlaintextStreamFilter.PlaintextStreamFilter_SimpleDelegatingStream_Success(Boolean useSsl)
--- End of stack trace from previous location ---
System.Net.Http.Functional.Tests.SocketsHttpHandler_HttpClientHandler_Finalization_Http11_Test.IncompleteResponseStream_ResponseDropped_CancelsRequestToServer [FAIL]
System.TimeoutException : The operation has timed out.
Stack Trace:
/_/src/libraries/System.Net.Http/tests/FunctionalTests/HttpClientHandlerTest.Finalization.cs(34,0): at System.Net.Http.Functional.Tests.HttpClientHandler_Finalization_Test.IncompleteResponseStream_ResponseDropped_CancelsRequestToServer()
--- End of stack trace from previous location ---
System.Net.Http.Functional.Tests.SocketsHttpHandlerTest_HttpClientHandlerTest_Headers_Http2.SendAsync_WithZeroLengthHeaderName_Throws [FAIL]
System.TimeoutException : The operation has timed out.
Stack Trace:
/_/src/libraries/System.Net.Http/tests/FunctionalTests/HttpClientHandlerTest.Headers.cs(408,0): at System.Net.Http.Functional.Tests.HttpClientHandlerTest_Headers.SendAsync_WithZeroLengthHeaderName_Throws()
--- End of stack trace from previous location ---
System.Net.Http.Functional.Tests.SocketsHttpHandler_HttpClientHandler_MaxResponseHeadersLength_Http11.LargeSingleHeader_ThrowsException(maxResponseHeadersLength: 1) [FAIL]
System.TimeoutException : The operation has timed out.
Stack Trace:
/_/src/libraries/Common/tests/System/Net/Http/HttpClientHandlerTest.MaxResponseHeadersLength.cs(72,0): at System.Net.Http.Functional.Tests.HttpClientHandler_MaxResponseHeadersLength_Test.LargeSingleHeader_ThrowsException(Int32 maxResponseHeadersLength)
--- End of stack trace from previous location ---
System.Net.Http.Functional.Tests.SocksProxyTest_Http2.TestLoopbackAsync(scheme: "socks4", useSsl: True, useAuth: True, host: "localhost") [FAIL]
System.TimeoutException : The operation has timed out.
Stack Trace:
/_/src/libraries/System.Net.Http/tests/FunctionalTests/SocksProxyTest.cs(43,0): at System.Net.Http.Functional.Tests.SocksProxyTest.TestLoopbackAsync(String scheme, Boolean useSsl, Boolean useAuth, String host)
--- End of stack trace from previous location ---
System.Net.Http.Functional.Tests.SocketsHttpHandler_SocketsHttpHandler_SecurityTest_Http11.SslOptions_CustomPolicy_IgnoresNameMismatch [FAIL]
System.TimeoutException : The operation has timed out.
Stack Trace:
/_/src/libraries/System.Net.Http/tests/FunctionalTests/SocketsHttpHandlerTest.cs(3829,0): at System.Net.Http.Functional.Tests.SocketsHttpHandler_SecurityTest.SslOptions_CustomPolicy_IgnoresNameMismatch()
--- End of stack trace from previous location ---
System.Net.Http.Functional.Tests.SocketsHttpHandlerTest_Cookies_Http2.GetAsync_AddMultipleCookieHeaders_CookiesSent [FAIL]
System.TimeoutException : The operation has timed out.
Stack Trace:
/_/src/libraries/Common/tests/System/Net/Http/HttpClientHandlerTest.Cookies.cs(154,0): at System.Net.Http.Functional.Tests.HttpClientHandlerTest_Cookies.GetAsync_AddMultipleCookieHeaders_CookiesSent()
--- End of stack trace from previous location ---
System.Net.Http.Functional.Tests.SyncHttpHandlerTest_Cookies.GetAsync_SetCookieContainer_CookieSent(cookieName: "ABC", cookieValue: "123", useCookies: True) [FAIL]
System.TimeoutException : The operation has timed out.
Stack Trace:
/_/src/libraries/Common/tests/System/Net/Http/HttpClientHandlerTest.Cookies.cs(69,0): at System.Net.Http.Functional.Tests.HttpClientHandlerTest_Cookies.GetAsync_SetCookieContainer_CookieSent(String cookieName, String cookieValue, Boolean useCookies)
--- End of stack trace from previous location ---
System.Net.Http.Functional.Tests.SocketsHttpHandler_SocketsHttpHandler_SecurityTest_Http3.SslOptions_InvalidName_Throws [FAIL]
System.TimeoutException : The operation has timed out.
Stack Trace:
/_/src/libraries/System.Net.Http/tests/FunctionalTests/SocketsHttpHandlerTest.cs(3798,0): at System.Net.Http.Functional.Tests.SocketsHttpHandler_SecurityTest.SslOptions_InvalidName_Throws()
--- End of stack trace from previous location ---
System.Net.Http.Functional.Tests.SocketsHttpHandler_HttpClientHandler_ConnectionPooling_Test.Http2_SmallConnectionTimeout_SubsequentRequestUsesDifferentConnection(timeoutPropertyName: "PooledConnectionLifetime") [FAIL]
System.TimeoutException : The operation has timed out.
Stack Trace:
/_/src/libraries/Common/tests/System/Net/Http/Http2LoopbackServer.cs(203,0): at System.Net.Test.Common.Http2LoopbackServerFactory.CreateServerAsync(Func`3 funcAsync, Int32 millisecondsTimeout)
/_/src/libraries/System.Net.Http/tests/FunctionalTests/SocketsHttpHandlerTest.cs(1556,0): at System.Net.Http.Functional.Tests.SocketsHttpHandler_HttpClientHandler_ConnectionPooling_Test.Http2_SmallConnectionTimeout_SubsequentRequestUsesDifferentConnection(String timeoutPropertyName)
--- End of stack trace from previous location ---
System.Net.Http.Functional.Tests.SocketsHttpHandler_HttpClientHandler_Http11_Cancellation_Test.GetAsync_CancelDuringResponseHeadersReceived_TaskCanceledQuickly(connectionClose: True, mode: Token) [FAIL]
Elapsed time 00:01:07.7107582 should be less than 60 seconds, was 67.7107582
Expected: True
Actual: False
Stack Trace:
/_/src/libraries/Common/tests/System/Net/Http/HttpClientHandlerTest.Cancellation.cs(615,0): at System.Net.Http.Functional.Tests.HttpClientHandler_Cancellation_Test.ValidateClientCancellationAsync(Func`1 clientBodyAsync)
/_/src/libraries/Common/tests/System/Net/Http/HttpClientHandlerTest.Cancellation.cs(122,0): at System.Net.Http.Functional.Tests.HttpClientHandler_Cancellation_Test.<>c__DisplayClass2_2.<<GetAsync_CancelDuringResponseHeadersReceived_TaskCanceledQuickly>b__0>d.MoveNext()
--- End of stack trace from previous location ---
/_/src/libraries/Common/tests/System/Net/Http/LoopbackServer.cs(101,0): at System.Net.Test.Common.LoopbackServer.CreateServerAsync(Func`2 funcAsync, Options options)
/_/src/libraries/Common/tests/System/Net/Http/HttpClientHandlerTest.Cancellation.cs(108,0): at System.Net.Http.Functional.Tests.HttpClientHandler_Cancellation_Test.GetAsync_CancelDuringResponseHeadersReceived_TaskCanceledQuickly(Boolean connectionClose, CancellationMode mode)
--- End of stack trace from previous location ---
System.Net.Http.Functional.Tests.SyncHttpHandler_HttpClientHandler_Authentication_Test.Proxy_DomainJoinedProxyServerUsesKerberos_Success [SKIP]
Condition(s) not met: "IsDomainJoinedServerAvailable"
System.Net.Http.Functional.Tests.SocketsHttpHandler_HttpClientHandler_Authentication_Test.Credentials_DomainJoinedServerUsesKerberos_UseIpAddressAndHostHeader_Success [SKIP]
Condition(s) not met: "IsDomainJoinedServerAvailable"
System.Net.Http.Functional.Tests.SocketsHttpHandler_HttpClientHandler_Authentication_Test.Proxy_DomainJoinedProxyServerUsesKerberos_Success [SKIP]
Condition(s) not met: "IsDomainJoinedServerAvailable"
System.Net.Http.Functional.Tests.SocketsHttpHandler_HttpClientHandler_Authentication_Test.Credentials_ServerUsesWindowsAuthentication_Success [SKIP]
Condition(s) not met: "IsWindowsServerAvailable"
System.Net.Http.Functional.Tests.SyncHttpHandler_HttpClientHandler_Authentication_Test.Credentials_DomainJoinedServerUsesKerberos_Success [SKIP]
Condition(s) not met: "IsDomainJoinedServerAvailable"
System.Net.Http.Functional.Tests.SyncHttpHandler_HttpClientHandler_Authentication_Test.Credentials_DomainJoinedServerUsesKerberos_UseIpAddressAndHostHeader_Success [SKIP]
Condition(s) not met: "IsDomainJoinedServerAvailable"
System.Net.Http.Functional.Tests.SocketsHttpHandler_HttpClientHandler_Authentication_Test.Credentials_DomainJoinedServerUsesKerberos_Success [SKIP]
Condition(s) not met: "IsDomainJoinedServerAvailable"
Finished: System.Net.Http.Functional.Tests
=== TEST EXECUTION SUMMARY ===
System.Net.Http.Functional.Tests Total: 4171, Errors: 0, Failed: 13, Skipped: 14, Time: 165.557s
Reactions are currently unavailable
Metadata
Metadata
Assignees
Labels
area-System.Net.Httptest-failuretest-run-coreTest failures in .NET Core test runsTest failures in .NET Core test runs
Activity
carlossanlop commentedon Sep 14, 2022
Also hit in this other release/7.0 backport PR: #75603
Queue: Libraries Test Run release coreclr Linux x64 Debug
Job results: https://dev.azure.com/dnceng-public/public/_build/results?buildId=16338&view=logs&j=d883cc86-ff08-5389-6fca-0d01451458f6&t=5fca1690-df52-529b-9100-c473a9350efd&l=104
Log: https://helixre107v0xdeko0k025g8.blob.core.windows.net/dotnet-runtime-refs-pull-75603-merge-736e589603e2479ea6/System.Net.Http.Functional.Tests.Attempt.3/2/console.acbacc93.log?helixlogtype=result
Callstack
carlossanlop commentedon Sep 19, 2022
Seen again in an unrelated
release/7.0-rc2backport PR: #75825Queue: Libraries Test Run release coreclr Linux x64 Debug
Job results: https://dev.azure.com/dnceng-public/public/_build/results?buildId=21762&view=logs&j=71afe768-e493-597c-228c-037834193aeb&t=fe87d331-02fa-5ca6-e382-69b0a11bb0f6&l=104
Log file: https://helixre107v0xdeko0k025g8.blob.core.windows.net/dotnet-runtime-refs-pull-75825-merge-44ab6e8c6f0e4e49ac/System.Net.Http.Functional.Tests.Attempt.3/3/console.d1f6c924.log?helixlogtype=result
Callstack
carlossanlop commentedon Sep 23, 2022
New hit in this
release/7.0dependencies update PR: #75890Callstack
carlossanlop commentedon Sep 26, 2022
Another hit in a different
release/7.0dependencies update PR: #75652Callstack
carlossanlop commentedon Jan 5, 2023
Seen again in a release/7.0 backport PR: #79192
Libraries Test Run release coreclr Linux x64 DebugSocketsHttpHandler_RequestContentLengthMismatchTest_Http2.ContentLength_DoesNotMatchRequestContentLength_Throws: https://helixre107v0xdeko0k025g8.blob.core.windows.net/dotnet-runtime-refs-pull-79192-merge-4491004e3e134e2f80/System.Net.Http.Functional.Tests/3/console.9d15c276.log?helixlogtype=resultSocketsHttpHandler_RequestContentLengthMismatchTest_Http2.ContentLength_DoesNotMatchRequestContentLength_Throws: https://helixre107v0xdeko0k025g8.blob.core.windows.net/dotnet-runtime-refs-pull-79192-merge-4491004e3e134e2f80/System.Net.Http.Functional.Tests/3/console.9d15c276.log?helixlogtype=resultSocketsHttpHandler_RequestContentLengthMismatchTest_Http2.ContentLength_DoesNotMatchRequestContentLength_Throws: https://helixre107v0xdeko0k025g8.blob.core.windows.net/dotnet-runtime-refs-pull-79192-merge-4491004e3e134e2f80/System.Net.Http.Functional.Tests/3/console.9d15c276.log?helixlogtype=resultkarelz commentedon Jan 23, 2023
@rzikm can you please check frequency on 7.0? Is it worth changing there?
If it is across multiple tests, perhaps we need to align the VMs used for servicing and main.
rzikm commentedon Jan 23, 2023
I see it affected 7 PRs in the last 30 days, a total of 62 tests have failed with a timeout.
ManickaP commentedon Jan 23, 2023
What I'm seeing is:
This might be environmental.
wfurt commentedon Jan 23, 2023
If I remember correctly,
RedHat.7still has old OpenSSL 1.0. And it full queue instead of container so timing can be different.With RHEL.9 out, do we still intend to support RedHat 7 with .NET 8.0 or do we plan to stick to the "two major versions back" @richlander?
ManickaP commentedon Feb 9, 2023
Triage: if we intend to support RH 7, we should investigate and fix this, if not, this could be closed when the queues get updated.
rzikm commentedon Jul 20, 2023
No recent hits on CI, closing.