Open
Description
Failing Test(s)
- Microsoft.AspNetCore.Server.IIS.NewHandler.FunctionalTests.ShutdownTests.ShutdownTimeoutIsApplied
Error Message
No entries matched by 'Application 'C:\h\w\A7840968\t\730abfe0baa140f3b3c7e6fb76cc139c' started successfully.'
Expected: True
Actual: False
Stacktrace
at Microsoft.AspNetCore.Server.IIS.FunctionalTests.EventLogHelpers.AssertEntry(String regexString, IEnumerable`1 entries, Boolean allowMultiple) in /_/src/Servers/IIS/IIS/test/Common.FunctionalTests/Infrastructure/EventLogHelpers.cs:line 72
at Microsoft.AspNetCore.Server.IIS.FunctionalTests.EventLogHelpers.VerifyEventLogEvents(IISDeploymentResult deploymentResult, String[] expectedRegexMatchString) in /_/src/Servers/IIS/IIS/test/Common.FunctionalTests/Infrastructure/EventLogHelpers.cs:line 44
at Microsoft.AspNetCore.Server.IIS.NewHandler.FunctionalTests.ShutdownTests.ShutdownTimeoutIsApplied() in /_/src/Servers/IIS/IIS/test/Common.LongTests/ShutdownTests.cs:line 51
Logs
[0.144s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: applicationhost.config path C:\h\w\A7840968\t\cb2e1eb3-07e6-4cba-83f2-762297533b5b
[0.172s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: W3SVC status Running
[0.200s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: Starting pool, state: Started
[1.143s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: Starting site, state: Started
[1.174s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: Site has started.
[1.225s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: Successfully finished IIS application directory setup.
[1.226s] Default Web Site Debug: Method: GET, RequestUri: 'http://localhost:54953/HelloWorld', Version: 1.1, Content: <null>, Headers:
{
}
[2.641s] Default Web Site Debug: StatusCode: 200, ReasonPhrase: 'OK', Version: 1.1, Content: System.Net.Http.HttpConnectionResponseContent, Headers:
{
Transfer-Encoding: chunked
Server: Microsoft-IIS/10.0
Date: Fri, 08 Dec 2023 18:34:40 GMT
}
[2.648s] Default Web Site Debug: Hello World
[2.659s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: Stopping site, state: Stopped
[2.662s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: Stopping pool, state: Stopping
[7.774s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: Site has stopped successfully.
[7.791s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: Host process shutting down.
[7.791s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [aspnetcorev2.dll] Initializing logs for 'C:\h\w\A7840968\w\AB8B09A5\e\x64\aspnetcorev2.dll'. Process Id: 7100.. File Version: 12.2.18316.0. Description: IIS ASP.NET Core Module V2. Commit: ce8cf65589734f82b0536c543aba5bd60d0a5a98.
[7.791s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [aspnetcorev2.dll] Resolving hostfxr parameters for application: 'C:\h\w\A7840968\p\dotnet-cli\dotnet.exe' arguments: '.\InProcessWebSite.dll HangOnStop' path: 'C:\h\w\A7840968\t\730abfe0baa140f3b3c7e6fb76cc139c\'
[7.791s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [aspnetcorev2.dll] Known dotnet.exe location: ''
[7.791s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [aspnetcorev2.dll] Process path 'C:\h\w\A7840968\p\dotnet-cli\dotnet.exe' is dotnet, treating application as portable
[7.791s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [aspnetcorev2.dll] Resolving absolute path to dotnet.exe from 'C:\h\w\A7840968\p\dotnet-cli\dotnet.exe'
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [aspnetcorev2.dll] Found dotnet.exe at 'C:\h\w\A7840968\p\dotnet-cli\dotnet.exe'
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [aspnetcorev2.dll] Resolving absolute path to hostfxr.dll from 'C:\h\w\A7840968\p\dotnet-cli\dotnet.exe'
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [aspnetcorev2.dll] hostfxr.dll located at 'C:\h\w\A7840968\p\dotnet-cli\host\fxr\9.0.0-alpha.1.23606.17\hostfxr.dll'
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [aspnetcorev2.dll] Converted argument '.\InProcessWebSite.dll' to 'C:\h\w\A7840968\t\730abfe0baa140f3b3c7e6fb76cc139c\.\InProcessWebSite.dll'
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [aspnetcorev2.dll] Parsed hostfxr options: dotnet location: 'C:\h\w\A7840968\p\dotnet-cli\dotnet.exe' hostfxr path: 'C:\h\w\A7840968\p\dotnet-cli\host\fxr\9.0.0-alpha.1.23606.17\hostfxr.dll' arguments:
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [aspnetcorev2.dll] Argument[0] = 'C:\h\w\A7840968\p\dotnet-cli\dotnet.exe'
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [aspnetcorev2.dll] Argument[1] = 'C:\h\w\A7840968\t\730abfe0baa140f3b3c7e6fb76cc139c\.\InProcessWebSite.dll'
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [aspnetcorev2.dll] Argument[2] = 'HangOnStop'
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [aspnetcorev2.dll] Loading request handler: 'C:\h\w\A7840968\t\730abfe0baa140f3b3c7e6fb76cc139c\x64\aspnetcorev2_inprocess.dll'
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [aspnetcorev2.dll] Creating handler application
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.191Z, PID: 7100] [aspnetcorev2_inprocess.dll] Initializing logs for 'C:\h\w\A7840968\t\730abfe0baa140f3b3c7e6fb76cc139c\x64\aspnetcorev2_inprocess.dll'. Process Id: 7100. File Version: 19.0.23342.0. Description: IIS ASP.NET Core Module V2 Request Handler. Commit: .
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.197Z, PID: 7100] [aspnetcorev2_inprocess.dll] Waiting for initialization
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.202Z, PID: 7100] [aspnetcorev2_inprocess.dll] Starting app_offline monitoring in application 'C:\h\w\A7840968\t\730abfe0baa140f3b3c7e6fb76cc139c\'
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.208Z, PID: 7100] [aspnetcorev2_inprocess.dll] Starting file watcher thread
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.208Z, PID: 7100] [aspnetcorev2_inprocess.dll] Starting in-process worker thread
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.219Z, PID: 7100] [aspnetcorev2_inprocess.dll] Resolving hostfxr parameters for application: 'C:\h\w\A7840968\p\dotnet-cli\dotnet.exe' arguments: '.\InProcessWebSite.dll HangOnStop' path: 'C:\h\w\A7840968\t\730abfe0baa140f3b3c7e6fb76cc139c\'
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.224Z, PID: 7100] [aspnetcorev2_inprocess.dll] Known dotnet.exe location: 'C:\h\w\A7840968\p\dotnet-cli\dotnet.exe'
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.229Z, PID: 7100] [aspnetcorev2_inprocess.dll] Process path 'C:\h\w\A7840968\p\dotnet-cli\dotnet.exe' is dotnet, treating application as portable
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.234Z, PID: 7100] [aspnetcorev2_inprocess.dll] Trying get_hostfxr_path with dotnet path as dotnet root
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.240Z, PID: 7100] [aspnetcorev2_inprocess.dll] hostfxr.dotnet_root: 'C:\h\w\A7840968\p\dotnet-cli'
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.246Z, PID: 7100] [aspnetcorev2_inprocess.dll] hostfxr.assembly_path: 'C:\h\w\A7840968\t\730abfe0baa140f3b3c7e6fb76cc139c\'
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.251Z, PID: 7100] [aspnetcorev2_inprocess.dll] dotnetExePath 'C:\h\w\A7840968\p\dotnet-cli\dotnet.exe'
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.257Z, PID: 7100] [aspnetcorev2_inprocess.dll] hostFxrDllpath 'C:\h\w\A7840968\p\dotnet-cli\host\fxr\9.0.0-alpha.1.23606.17\hostfxr.dll'
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.263Z, PID: 7100] [aspnetcorev2_inprocess.dll] Converted argument '.\InProcessWebSite.dll' to 'C:\h\w\A7840968\t\730abfe0baa140f3b3c7e6fb76cc139c\.\InProcessWebSite.dll'
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.268Z, PID: 7100] [aspnetcorev2_inprocess.dll] Parsed hostfxr options: dotnet location: 'C:\h\w\A7840968\p\dotnet-cli\dotnet.exe' hostfxr path: 'C:\h\w\A7840968\p\dotnet-cli\host\fxr\9.0.0-alpha.1.23606.17\hostfxr.dll' arguments:
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.285Z, PID: 7100] [aspnetcorev2_inprocess.dll] Argument[0] = 'C:\h\w\A7840968\p\dotnet-cli\dotnet.exe'
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.290Z, PID: 7100] [aspnetcorev2_inprocess.dll] Argument[1] = 'C:\h\w\A7840968\t\730abfe0baa140f3b3c7e6fb76cc139c\.\InProcessWebSite.dll'
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.295Z, PID: 7100] [aspnetcorev2_inprocess.dll] Argument[2] = 'HangOnStop'
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.301Z, PID: 7100] [aspnetcorev2_inprocess.dll] Setting environment variable ASPNETCORE_DETAILEDERRORS=true
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.309Z, PID: 7100] [aspnetcorev2_inprocess.dll] Setting environment variable ASPNETCORE_IIS_HTTPAUTH=anonymous;
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.317Z, PID: 7100] [aspnetcorev2_inprocess.dll] Setting environment variable ASPNETCORE_IIS_PHYSICAL_PATH=C:\h\w\A7840968\t\730abfe0baa140f3b3c7e6fb76cc139c\
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.323Z, PID: 7100] [aspnetcorev2_inprocess.dll] Loading hostfxr from location C:\h\w\A7840968\p\dotnet-cli\host\fxr\9.0.0-alpha.1.23606.17\hostfxr.dll
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.329Z, PID: 7100] [aspnetcorev2_inprocess.dll] Initial Dll directory: '', current directory: 'c:\windows\system32\inetsrv'
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.337Z, PID: 7100] [aspnetcorev2_inprocess.dll] Setting dll directory to c:\windows\system32\inetsrv
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:39.342Z, PID: 7100] [aspnetcorev2_inprocess.dll] Setting current directory to C:\h\w\A7840968\t\730abfe0baa140f3b3c7e6fb76cc139c\
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:40.173Z, PID: 7100] [aspnetcorev2_inprocess.dll] In-process callbacks set
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:40.180Z, PID: 7100] [aspnetcorev2_inprocess.dll] Event Log: 'Application 'C:\h\w\A7840968\t\730abfe0baa140f3b3c7e6fb76cc139c\' started successfully.'
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: End Event Log Message.
[7.792s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [aspnetcorev2.dll] ASPNET_CORE_GLOBAL_MODULE::OnGlobalStopListening
[7.793s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [aspnetcorev2.dll] Stopping application '/LM/W3SVC/1/ROOT'
[7.793s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:40.417Z, PID: 7100] [aspnetcorev2_inprocess.dll] Stopping file watching.
[7.793s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:40.423Z, PID: 7100] [aspnetcorev2_inprocess.dll] Waiting for file watcher thread to exit.
[7.793s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:40.423Z, PID: 7100] [aspnetcorev2_inprocess.dll] Stopping file watcher thread
[7.793s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:40.434Z, PID: 7100] [aspnetcorev2_inprocess.dll] Waiting for file watcher thread to exit.
[7.793s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:40.440Z, PID: 7100] [aspnetcorev2_inprocess.dll] Stopping CLR
[7.793s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:40.446Z, PID: 7100] [aspnetcorev2_inprocess.dll] Starting shutdown sequence 0
[7.793s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:40.447Z, PID: 7100] [aspnetcorev2_inprocess.dll] Drained all requests, notifying managed.
[7.793s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:40.449Z, PID: 7100] [aspnetcorev2_inprocess.dll] Waiting for 0 requests to drain
[7.793s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:41.456Z, PID: 7100] [aspnetcorev2_inprocess.dll] Clr thread wait ended: clrThreadExited: 0
[7.793s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:41.466Z, PID: 7100] [aspnetcorev2_inprocess.dll] Event Log: 'Failed to gracefully shutdown application 'MACHINE/WEBROOT/APPHOST/DEFAULT WEB SITE'.'
[7.793s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: End Event Log Message.
[7.793s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:41.472Z, PID: 7100] [aspnetcorev2_inprocess.dll] Canceling standard stream pipe reader
[7.793s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [2023-12-08T18:34:41.477Z, PID: 7100] [aspnetcorev2_inprocess.dll] Stopping in-process worker thread
[7.793s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [aspnetcorev2.dll] ASPNET_CORE_GLOBAL_MODULE::Terminate
[7.822s] Microsoft.AspNetCore.Server.IntegrationTesting.IIS.IISDeployer Information: [Time]: Total time taken for this test variation '7.8154531' seconds
[7.829s] Microsoft.AspNetCore.Server.IIS.NewHandler.FunctionalTests.ShutdownTests Error: Test threw an exception.
Xunit.Sdk.TrueException: No entries matched by 'Application 'C:\\h\\w\\A7840968\\t\\730abfe0baa140f3b3c7e6fb76cc139c\\' started successfully.'
Expected: True
Actual: False
at Xunit.Assert.True(Nullable`1 condition, String userMessage) in /_/src/xunit.assert/Asserts/BooleanAsserts.cs:line 132
at Xunit.Assert.True(Boolean condition, String userMessage) in /_/src/xunit.assert/Asserts/BooleanAsserts.cs:line 116
at Microsoft.AspNetCore.Server.IIS.FunctionalTests.EventLogHelpers.AssertEntry(String regexString, IEnumerable`1 entries, Boolean allowMultiple) in /_/src/Servers/IIS/IIS/test/Common.FunctionalTests/Infrastructure/EventLogHelpers.cs:line 72
at Microsoft.AspNetCore.Server.IIS.FunctionalTests.EventLogHelpers.VerifyEventLogEvents(IISDeploymentResult deploymentResult, String[] expectedRegexMatchString) in /_/src/Servers/IIS/IIS/test/Common.FunctionalTests/Infrastructure/EventLogHelpers.cs:line 44
at Microsoft.AspNetCore.Server.IIS.NewHandler.FunctionalTests.ShutdownTests.ShutdownTimeoutIsApplied() in /_/src/Servers/IIS/IIS/test/Common.LongTests/ShutdownTests.cs:line 51
at Xunit.Sdk.TestInvoker`1.<>c__DisplayClass48_0.<<InvokeTestMethodAsync>b__1>d.MoveNext() in /_/src/xunit.execution/Sdk/Frameworks/Runners/TestInvoker.cs:line 264
--- End of stack trace from previous location ---
at Xunit.Sdk.ExecutionTimer.AggregateAsync(Func`1 asyncAction) in /_/src/xunit.execution/Sdk/Frameworks/ExecutionTimer.cs:line 48
at Xunit.Sdk.ExceptionAggregator.RunAsync(Func`1 code) in /_/src/xunit.core/Sdk/ExceptionAggregator.cs:line 90
Build
https://dev.azure.com/dnceng-public/public/_build/results?buildId=493506