Failing test: Aspire.Hosting.Tests.DistributedApplicationTests.StartResourceForcesStart

[radical]

Build Information

Build: https://github.com/dotnet/aspire/actions/runs/15920180292
Build error leg or test failing: Aspire.Hosting.Tests.DistributedApplicationTests.StartResourceForcesStart
Pull request:

Error Message

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

{
  "ErrorMessage": "",
  "ErrorPattern": "",
  "BuildRetry": false,
  "ExcludeConsoleLog": false
}

System.TimeoutException : The operation at /_/tests/Aspire.Hosting.Tests/DistributedApplicationTests.cs:149 timed out after reaching the limit of 180000ms.   at Microsoft.AspNetCore.InternalTesting.AsyncTestHelpers.TimeoutAfter[T](Task`1 task, TimeSpan timeout, String filePath, Int32 lineNumber) in /_/tests/Shared/AsyncTestHelpers.cs:line 143
   at Aspire.Hosting.Tests.DistributedApplicationTests.StartResourceForcesStart() in /_/tests/Aspire.Hosting.Tests/DistributedApplicationTests.cs:line 149
--- End of stack trace from previous location ---

Stdout

Details

| [2025-06-27T07:00:16] Microsoft.Extensions.Hosting.Internal.Host Debug: Hosting starting
| [2025-06-27T07:00:16] Aspire.Hosting.DistributedApplication Information: Aspire version: 9.4.0-ci
| [2025-06-27T07:00:16] Aspire.Hosting.DistributedApplication Information: Distributed application starting.
| [2025-06-27T07:00:16] Aspire.Hosting.DistributedApplication Information: Application host directory is: D:\a\aspire\aspire\tests\Aspire.Hosting.Tests
| [2025-06-27T07:00:16] Aspire.Hosting.Cli.BackchannelService Debug: Backchannel socket path was not specified.
| [2025-06-27T07:00:16] Aspire.Hosting.ApplicationModel.ResourceNotificationService Debug: Waiting for resource 'force-resource-start-serviceb' to match predicate.
| [2025-06-27T07:00:17] Aspire.Hosting.Dcp.DcpHost Information: Starting DCP with arguments: start-apiserver --monitor 4940 --detach --kubeconfig "C:\Users\runneradmin\AppData\Local\Temp\aspire.mydygt0o.s2f\kubeconfig"
| [2025-06-27T07:00:17] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 1 Resource force-resource-start-servicea/force-resource-start-servicea-peqqfqxv-3977a2ce update published: ResourceType = ProjectResource, CreationTimeStamp = (null), State = { Text = (null), Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = False, ExitCode = (null), Urls = {  }, EnvironmentVariables = {  }, Properties = {  }, HealthReports = { dummy_healthcheck = (null) }, Commands = { resource-start (Start) = Disabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:17] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 1 Resource force-resource-start-serviceb/force-resource-start-serviceb-msjmqgkd-3977a2ce update published: ResourceType = ProjectResource, CreationTimeStamp = (null), State = { Text = (null), Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = False, ExitCode = (null), Urls = {  }, EnvironmentVariables = {  }, Properties = {  }, HealthReports = {  }, Commands = { resource-start (Start) = Disabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:17] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 1 Resource force-resource-start-servicec/force-resource-start-servicec-sjwjegee-3977a2ce update published: ResourceType = ProjectResource, CreationTimeStamp = (null), State = { Text = (null), Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = False, ExitCode = (null), Urls = {  }, EnvironmentVariables = {  }, Properties = {  }, HealthReports = {  }, Commands = { resource-start (Start) = Disabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:17] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 1 Resource force-resource-start-workera/force-resource-start-workera-ptwschwx-3977a2ce update published: ResourceType = ProjectResource, CreationTimeStamp = (null), State = { Text = (null), Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = False, ExitCode = (null), Urls = {  }, EnvironmentVariables = {  }, Properties = {  }, HealthReports = {  }, Commands = { resource-start (Start) = Disabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:17] Aspire.Hosting.Dcp.DcpExecutor Debug: Watching over DCP Endpoint resources.
| [2025-06-27T07:00:17] Aspire.Hosting.Dcp.DcpExecutor Debug: Watching over DCP Service resources.
| [2025-06-27T07:00:17] Aspire.Hosting.Dcp.DcpExecutor Debug: Watching over DCP Container resources.
| [2025-06-27T07:00:17] Aspire.Hosting.Dcp.DcpExecutor Debug: Watching over DCP Executable resources.
| [2025-06-27T07:00:18] Aspire.Hosting.Dcp.dcp.start-apiserver.api-server Information: Starting API server...
| [2025-06-27T07:00:18] Aspire.Hosting.Dcp.dcp.start-apiserver.api-server Information: API server started	{"Address": "::1", "Port": 57615}
| [2025-06-27T07:00:18] Aspire.Hosting.Dcp.dcp.start-apiserver.dcp-host Information: Starting DCP controller host
| [2025-06-27T07:00:18] Aspire.Hosting.Dcp.dcp.start-apiserver.dcp-host Information: Started all services	{"count": 1}
| [2025-06-27T07:00:18] Aspire.Hosting.Dcp.KubernetesService Debug: Successfully read Kubernetes configuration from 'C:\Users\runneradmin\AppData\Local\Temp\aspire.mydygt0o.s2f\kubeconfig' after 993 milliseconds.
| [2025-06-27T07:00:18] Aspire.Hosting.Dcp.dcpctrl.IdeExecutableRunner Information: Executables cannot be started via IDE: missing required environment variable 'DEBUG_SESSION_PORT'
| [2025-06-27T07:00:18] Aspire.Hosting.Dcp.dcpctrl Information: starting controller manager
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 2 Resource force-resource-start-servicea/force-resource-start-servicea-peqqfqxv-3977a2ce update published: ResourceType = ProjectResource, CreationTimeStamp = (null), State = { Text = (null), Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = False, ExitCode = (null), Urls = { http = http://localhost:57620 }, EnvironmentVariables = {  }, Properties = {  }, HealthReports = { dummy_healthcheck = (null) }, Commands = { resource-start (Start) = Disabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 2 Resource force-resource-start-serviceb/force-resource-start-serviceb-msjmqgkd-3977a2ce update published: ResourceType = ProjectResource, CreationTimeStamp = (null), State = { Text = (null), Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = False, ExitCode = (null), Urls = { http = http://localhost:57621 }, EnvironmentVariables = {  }, Properties = {  }, HealthReports = {  }, Commands = { resource-start (Start) = Disabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 2 Resource force-resource-start-servicec/force-resource-start-servicec-sjwjegee-3977a2ce update published: ResourceType = ProjectResource, CreationTimeStamp = (null), State = { Text = (null), Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = False, ExitCode = (null), Urls = { http = http://localhost:57626 }, EnvironmentVariables = {  }, Properties = {  }, HealthReports = {  }, Commands = { resource-start (Start) = Disabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 2 Resource force-resource-start-workera/force-resource-start-workera-ptwschwx-3977a2ce update published: ResourceType = ProjectResource, CreationTimeStamp = (null), State = { Text = (null), Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = False, ExitCode = (null), Urls = {  }, EnvironmentVariables = {  }, Properties = {  }, HealthReports = {  }, Commands = { resource-start (Start) = Disabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 3 Resource force-resource-start-servicea/force-resource-start-servicea-peqqfqxv-3977a2ce update published: ResourceType = Project, CreationTimeStamp = (null), State = { Text = (null), Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = False, ExitCode = (null), Urls = { http = http://localhost:57620 }, EnvironmentVariables = {  }, Properties = { executable.path = dotnet executable.workDir = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceA executable.args =  executable.pid = (null) project.path = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceA\TestProject.ServiceA.csproj resource.appArgs = (null) resource.appArgsSensitivity = (null) }, HealthReports = { dummy_healthcheck = (null) }, Commands = { resource-start (Start) = Disabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Debug: Resource force-resource-start-servicea/force-resource-start-servicea-peqqfqxv-3977a2ce changed state: Starting
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 4 Resource force-resource-start-servicea/force-resource-start-servicea-peqqfqxv-3977a2ce update published: ResourceType = Project, CreationTimeStamp = (null), State = { Text = Starting, Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = False, ExitCode = (null), Urls = { http = http://localhost:57620 }, EnvironmentVariables = {  }, Properties = { executable.path = dotnet executable.workDir = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceA executable.args =  executable.pid = (null) project.path = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceA\TestProject.ServiceA.csproj resource.appArgs = (null) resource.appArgsSensitivity = (null) }, HealthReports = { dummy_healthcheck = (null) }, Commands = { resource-start (Start) = Disabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 3 Resource force-resource-start-serviceb/force-resource-start-serviceb-msjmqgkd-3977a2ce update published: ResourceType = Project, CreationTimeStamp = (null), State = { Text = (null), Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = False, ExitCode = (null), Urls = { http = http://localhost:57621 }, EnvironmentVariables = {  }, Properties = { executable.path = dotnet executable.workDir = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceB executable.args =  executable.pid = (null) project.path = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceB\TestProject.ServiceB.csproj resource.appArgs = (null) resource.appArgsSensitivity = (null) }, HealthReports = {  }, Commands = { resource-start (Start) = Disabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Debug: Resource force-resource-start-serviceb/force-resource-start-serviceb-msjmqgkd-3977a2ce changed state: Starting
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 4 Resource force-resource-start-serviceb/force-resource-start-serviceb-msjmqgkd-3977a2ce update published: ResourceType = Project, CreationTimeStamp = (null), State = { Text = Starting, Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = False, ExitCode = (null), Urls = { http = http://localhost:57621 }, EnvironmentVariables = {  }, Properties = { executable.path = dotnet executable.workDir = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceB executable.args =  executable.pid = (null) project.path = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceB\TestProject.ServiceB.csproj resource.appArgs = (null) resource.appArgsSensitivity = (null) }, HealthReports = {  }, Commands = { resource-start (Start) = Disabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Debug: Resource force-resource-start-serviceb/force-resource-start-serviceb-msjmqgkd-3977a2ce changed state: Starting -> Waiting
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 5 Resource force-resource-start-serviceb/force-resource-start-serviceb-msjmqgkd-3977a2ce update published: ResourceType = Project, CreationTimeStamp = (null), State = { Text = Waiting, Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = False, ExitCode = (null), Urls = { http = http://localhost:57621 }, EnvironmentVariables = {  }, Properties = { executable.path = dotnet executable.workDir = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceB executable.args =  executable.pid = (null) project.path = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceB\TestProject.ServiceB.csproj resource.appArgs = (null) resource.appArgsSensitivity = (null) }, HealthReports = {  }, Commands = { resource-start (Start) = Enabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Debug: Waiting for resource 'force-resource-start-serviceb' to match predicate.
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 3 Resource force-resource-start-servicec/force-resource-start-servicec-sjwjegee-3977a2ce update published: ResourceType = Project, CreationTimeStamp = (null), State = { Text = (null), Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = False, ExitCode = (null), Urls = { http = http://localhost:57626 }, EnvironmentVariables = {  }, Properties = { executable.path = dotnet executable.workDir = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceC executable.args =  executable.pid = (null) project.path = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceC\TestProject.ServiceC.csproj resource.appArgs = (null) resource.appArgsSensitivity = (null) }, HealthReports = {  }, Commands = { resource-start (Start) = Disabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Debug: Resource force-resource-start-servicec/force-resource-start-servicec-sjwjegee-3977a2ce changed state: Starting
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 4 Resource force-resource-start-servicec/force-resource-start-servicec-sjwjegee-3977a2ce update published: ResourceType = Project, CreationTimeStamp = (null), State = { Text = Starting, Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = False, ExitCode = (null), Urls = { http = http://localhost:57626 }, EnvironmentVariables = {  }, Properties = { executable.path = dotnet executable.workDir = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceC executable.args =  executable.pid = (null) project.path = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceC\TestProject.ServiceC.csproj resource.appArgs = (null) resource.appArgsSensitivity = (null) }, HealthReports = {  }, Commands = { resource-start (Start) = Disabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:18] Aspire.Hosting.Tests.Resources.force-resource-start-serviceb Information: 1: 2025-06-27T07:00:18.5482915Z Waiting for resource 'force-resource-start-servicea' to enter the 'Running' state.
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Debug: Finished waiting for resource 'force-resource-start-serviceb'.
| [2025-06-27T07:00:18] Aspire.Hosting.Tests.DistributedApplicationTests Information: Force resource to start.
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 3 Resource force-resource-start-workera/force-resource-start-workera-ptwschwx-3977a2ce update published: ResourceType = Project, CreationTimeStamp = (null), State = { Text = (null), Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = False, ExitCode = (null), Urls = {  }, EnvironmentVariables = {  }, Properties = { executable.path = dotnet executable.workDir = D:\a\aspire\aspire\tests\testproject\TestProject.WorkerA executable.args =  executable.pid = (null) project.path = D:\a\aspire\aspire\tests\testproject\TestProject.WorkerA\TestProject.WorkerA.csproj resource.appArgs = (null) resource.appArgsSensitivity = (null) }, HealthReports = {  }, Commands = { resource-start (Start) = Disabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Debug: Resource force-resource-start-workera/force-resource-start-workera-ptwschwx-3977a2ce changed state: Starting
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 4 Resource force-resource-start-workera/force-resource-start-workera-ptwschwx-3977a2ce update published: ResourceType = Project, CreationTimeStamp = (null), State = { Text = Starting, Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = False, ExitCode = (null), Urls = {  }, EnvironmentVariables = {  }, Properties = { executable.path = dotnet executable.workDir = D:\a\aspire\aspire\tests\testproject\TestProject.WorkerA executable.args =  executable.pid = (null) project.path = D:\a\aspire\aspire\tests\testproject\TestProject.WorkerA\TestProject.WorkerA.csproj resource.appArgs = (null) resource.appArgsSensitivity = (null) }, HealthReports = {  }, Commands = { resource-start (Start) = Disabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Debug: Resource force-resource-start-serviceb/force-resource-start-serviceb-msjmqgkd-3977a2ce changed state: Waiting -> Starting
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 6 Resource force-resource-start-serviceb/force-resource-start-serviceb-msjmqgkd-3977a2ce update published: ResourceType = Project, CreationTimeStamp = (null), State = { Text = Starting, Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = False, ExitCode = (null), Urls = { http = http://localhost:57621 }, EnvironmentVariables = {  }, Properties = { executable.path = dotnet executable.workDir = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceB executable.args =  executable.pid = (null) project.path = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceB\TestProject.ServiceB.csproj resource.appArgs = (null) resource.appArgsSensitivity = (null) }, HealthReports = {  }, Commands = { resource-start (Start) = Disabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:18] Aspire.Hosting.Dcp.DcpExecutor Trace: Updating application model resource force-resource-start-servicea with Executable resource force-resource-start-servicea-peqqfqxv-3977a2ce
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Debug: Finished waiting for resource 'force-resource-start-serviceb'.
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Debug: Waiting for resource 'force-resource-start-serviceb' to match predicate.
| [2025-06-27T07:00:18] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 5 Resource force-resource-start-servicea/force-resource-start-servicea-peqqfqxv-3977a2ce update published: ResourceType = Project, CreationTimeStamp = 2025-06-27T07:00:18, State = { Text = , Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = False, ExitCode = (null), Urls = { http = http://localhost:57620 }, EnvironmentVariables = {  }, Properties = { executable.path = dotnet executable.workDir = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceA executable.args =  executable.pid = 0 project.path = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceA\TestProject.ServiceA.csproj resource.appArgs = --test1, --test2 resource.appArgsSensitivity = 0, 0 }, HealthReports = { dummy_healthcheck = (null) }, Commands = { resource-start (Start) = Disabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:18] Aspire.Hosting.Dcp.DcpExecutor Trace: Updating application model resource force-resource-start-servicea with Executable resource force-resource-start-servicea-p

... (snip) ...

... sts.Resources.force-resource-start-servicea Information: 12: 2025-06-27T07:00:18.8450000Z 6.0.136 [C:\Program Files\dotnet\sdk]
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-servicea Information: 13: 2025-06-27T07:00:18.8450000Z 6.0.203 [C:\Program Files\dotnet\sdk]
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-servicea Information: 14: 2025-06-27T07:00:18.8450000Z 6.0.321 [C:\Program Files\dotnet\sdk]
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-servicea Information: 15: 2025-06-27T07:00:18.8450000Z 6.0.428 [C:\Program Files\dotnet\sdk]
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-servicea Information: 16: 2025-06-27T07:00:18.8450000Z 8.0.117 [C:\Program Files\dotnet\sdk]
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-servicea Information: 17: 2025-06-27T07:00:18.8450000Z 8.0.206 [C:\Program Files\dotnet\sdk]
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-servicea Information: 18: 2025-06-27T07:00:18.8450000Z 8.0.314 [C:\Program Files\dotnet\sdk]
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-servicea Information: 19: 2025-06-27T07:00:18.8450000Z 8.0.411 [C:\Program Files\dotnet\sdk]
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-servicea Information: 20: 2025-06-27T07:00:18.8450000Z 9.0.107 [C:\Program Files\dotnet\sdk]
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-servicea Information: 21: 2025-06-27T07:00:18.8450000Z 9.0.205 [C:\Program Files\dotnet\sdk]
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-servicea Information: 22: 2025-06-27T07:00:18.8450000Z 9.0.301 [C:\Program Files\dotnet\sdk]
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-servicea Error: 23: 2025-06-27T07:00:18.8450000Z Install the [10.0.100-preview.5.25277.114] .NET SDK or update [D:\a\aspire\aspire\global.json] to match an installed SDK.
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-servicea Error: 24: 2025-06-27T07:00:18.8450000Z 
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-servicea Error: 25: 2025-06-27T07:00:18.8450000Z Learn about SDK resolution:
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-servicea Error: 26: 2025-06-27T07:00:18.8450000Z https://aka.ms/dotnet/sdk-not-found
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-workera Information: 11: 2025-06-27T07:00:18.8450000Z 6.0.136 [C:\Program Files\dotnet\sdk]
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-workera Error: 12: 2025-06-27T07:00:18.8460000Z 
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-workera Information: 13: 2025-06-27T07:00:18.8450000Z 6.0.203 [C:\Program Files\dotnet\sdk]
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-workera Error: 14: 2025-06-27T07:00:18.8460000Z Install the [10.0.100-preview.5.25277.114] .NET SDK or update [D:\a\aspire\aspire\global.json] to match an installed SDK.
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-workera Information: 15: 2025-06-27T07:00:18.8450000Z 6.0.321 [C:\Program Files\dotnet\sdk]
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-workera Error: 16: 2025-06-27T07:00:18.8460000Z 
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-workera Information: 17: 2025-06-27T07:00:18.8450000Z 6.0.428 [C:\Program Files\dotnet\sdk]
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-workera Error: 18: 2025-06-27T07:00:18.8460000Z Learn about SDK resolution:
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-workera Information: 19: 2025-06-27T07:00:18.8450000Z 8.0.117 [C:\Program Files\dotnet\sdk]
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-workera Error: 20: 2025-06-27T07:00:18.8460000Z https://aka.ms/dotnet/sdk-not-found
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-workera Information: 21: 2025-06-27T07:00:18.8450000Z 8.0.206 [C:\Program Files\dotnet\sdk]
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-workera Information: 22: 2025-06-27T07:00:18.8450000Z 8.0.314 [C:\Program Files\dotnet\sdk]
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-workera Information: 23: 2025-06-27T07:00:18.8450000Z 8.0.411 [C:\Program Files\dotnet\sdk]
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-workera Information: 24: 2025-06-27T07:00:18.8450000Z 9.0.107 [C:\Program Files\dotnet\sdk]
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-workera Information: 25: 2025-06-27T07:00:18.8450000Z 9.0.205 [C:\Program Files\dotnet\sdk]
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-workera Information: 26: 2025-06-27T07:00:18.8450000Z 9.0.301 [C:\Program Files\dotnet\sdk]
| [2025-06-27T07:00:19] Aspire.Hosting.Dcp.DcpExecutor Trace: Updating application model resource force-resource-start-workera with Executable resource force-resource-start-workera-ptwschwx-3977a2ce
| [2025-06-27T07:00:19] Aspire.Hosting.ApplicationModel.ResourceNotificationService Debug: Resource force-resource-start-workera/force-resource-start-workera-ptwschwx-3977a2ce changed state: Running -> Finished
| [2025-06-27T07:00:19] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 10 Resource force-resource-start-workera/force-resource-start-workera-ptwschwx-3977a2ce update published: ResourceType = Project, CreationTimeStamp = 2025-06-27T07:00:18, State = { Text = Finished, Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = True, ExitCode = -2147450735, Urls = {  }, EnvironmentVariables = { DOTNET_ENVIRONMENT = Development DOTNET_LAUNCH_PROFILE = TestProject.WorkerA DOTNET_SYSTEM_CONSOLE_ALLOW_ANSI_COLOR_REDIRECTION = true LOGGING__CONSOLE__FORMATTERNAME = simple OTEL_DOTNET_EXPERIMENTAL_OTLP_EMIT_EVENT_LOG_ATTRIBUTES = true OTEL_DOTNET_EXPERIMENTAL_OTLP_EMIT_EXCEPTION_LOG_ATTRIBUTES = true OTEL_DOTNET_EXPERIMENTAL_OTLP_RETRY = in_memory OTEL_EXPORTER_OTLP_ENDPOINT = http://localhost:18889 OTEL_EXPORTER_OTLP_PROTOCOL = grpc OTEL_RESOURCE_ATTRIBUTES = service.instance.id=ptwschwx OTEL_SERVICE_NAME = force-resource-start-workera }, Properties = { executable.path = dotnet executable.workDir = D:\a\aspire\aspire\tests\testproject\TestProject.WorkerA executable.args = run, --no-build, --project, D:\a\aspire\aspire\tests\testproject\TestProject.WorkerA\TestProject.WorkerA.csproj, -c, Debug, --no-launch-profile executable.pid = 3952 project.path = D:\a\aspire\aspire\tests\testproject\TestProject.WorkerA\TestProject.WorkerA.csproj resource.appArgs =  resource.appArgsSensitivity =  }, HealthReports = {  }, Commands = { resource-start (Start) = Enabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:19] Aspire.Hosting.Health.ResourceHealthCheckService Debug: Stopping health monitoring for resource 'force-resource-start-workera'.
| [2025-06-27T07:00:19] Aspire.Hosting.Dcp.DcpExecutor Debug: Delete request for 'force-resource-start-serviceb-msjmqgkd-3977a2ce' returned NotFound.
| [2025-06-27T07:00:19] Aspire.Hosting.ApplicationModel.ResourceNotificationService Debug: Resource force-resource-start-serviceb/force-resource-start-serviceb-msjmqgkd-3977a2ce changed state: Finished -> Starting
| [2025-06-27T07:00:19] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 20 Resource force-resource-start-serviceb/force-resource-start-serviceb-msjmqgkd-3977a2ce update published: ResourceType = Project, CreationTimeStamp = 2025-06-27T07:00:18, State = { Text = Starting, Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = True, ExitCode = -2147450735, Urls = { http = http://localhost:57621 }, EnvironmentVariables = { ASPNETCORE_ENVIRONMENT = Development ASPNETCORE_URLS = http://localhost:57629 DOTNET_LAUNCH_PROFILE = http DOTNET_SYSTEM_CONSOLE_ALLOW_ANSI_COLOR_REDIRECTION = true LOGGING__CONSOLE__FORMATTERNAME = simple OTEL_DOTNET_EXPERIMENTAL_OTLP_EMIT_EVENT_LOG_ATTRIBUTES = true OTEL_DOTNET_EXPERIMENTAL_OTLP_EMIT_EXCEPTION_LOG_ATTRIBUTES = true OTEL_DOTNET_EXPERIMENTAL_OTLP_RETRY = in_memory OTEL_EXPORTER_OTLP_ENDPOINT = http://localhost:18889 OTEL_EXPORTER_OTLP_PROTOCOL = grpc OTEL_RESOURCE_ATTRIBUTES = service.instance.id=msjmqgkd OTEL_SERVICE_NAME = force-resource-start-serviceb }, Properties = { executable.path = dotnet executable.workDir = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceB executable.args = run, --no-build, --project, D:\a\aspire\aspire\tests\testproject\TestProject.ServiceB\TestProject.ServiceB.csproj, -c, Debug, --no-launch-profile executable.pid = 0 project.path = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceB\TestProject.ServiceB.csproj resource.appArgs =  resource.appArgsSensitivity =  }, HealthReports = {  }, Commands = { resource-start (Start) = Disabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-serviceb Information: 28: 2025-06-27T07:00:19.2143040Z Waiting for resource 'force-resource-start-servicea' to enter the 'Running' state.
| [2025-06-27T07:00:19] Aspire.Hosting.ApplicationModel.ResourceNotificationService Debug: Resource force-resource-start-serviceb/force-resource-start-serviceb-msjmqgkd-3977a2ce changed state: Starting -> Waiting
| [2025-06-27T07:00:19] Aspire.Hosting.ApplicationModel.ResourceNotificationService Debug: Finished waiting for resource 'force-resource-start-serviceb'.
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.DistributedApplicationTests Information: Force resource to start.
| [2025-06-27T07:00:19] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 21 Resource force-resource-start-serviceb/force-resource-start-serviceb-msjmqgkd-3977a2ce update published: ResourceType = Project, CreationTimeStamp = 2025-06-27T07:00:18, State = { Text = Waiting, Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = True, ExitCode = -2147450735, Urls = { http = http://localhost:57621 }, EnvironmentVariables = { ASPNETCORE_ENVIRONMENT = Development ASPNETCORE_URLS = http://localhost:57629 DOTNET_LAUNCH_PROFILE = http DOTNET_SYSTEM_CONSOLE_ALLOW_ANSI_COLOR_REDIRECTION = true LOGGING__CONSOLE__FORMATTERNAME = simple OTEL_DOTNET_EXPERIMENTAL_OTLP_EMIT_EVENT_LOG_ATTRIBUTES = true OTEL_DOTNET_EXPERIMENTAL_OTLP_EMIT_EXCEPTION_LOG_ATTRIBUTES = true OTEL_DOTNET_EXPERIMENTAL_OTLP_RETRY = in_memory OTEL_EXPORTER_OTLP_ENDPOINT = http://localhost:18889 OTEL_EXPORTER_OTLP_PROTOCOL = grpc OTEL_RESOURCE_ATTRIBUTES = service.instance.id=msjmqgkd OTEL_SERVICE_NAME = force-resource-start-serviceb }, Properties = { executable.path = dotnet executable.workDir = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceB executable.args = run, --no-build, --project, D:\a\aspire\aspire\tests\testproject\TestProject.ServiceB\TestProject.ServiceB.csproj, -c, Debug, --no-launch-profile executable.pid = 0 project.path = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceB\TestProject.ServiceB.csproj resource.appArgs =  resource.appArgsSensitivity =  }, HealthReports = {  }, Commands = { resource-start (Start) = Enabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:19] Aspire.Hosting.ApplicationModel.ResourceNotificationService Debug: Resource force-resource-start-serviceb/force-resource-start-serviceb-msjmqgkd-3977a2ce changed state: Waiting -> Starting
| [2025-06-27T07:00:19] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 22 Resource force-resource-start-serviceb/force-resource-start-serviceb-msjmqgkd-3977a2ce update published: ResourceType = Project, CreationTimeStamp = 2025-06-27T07:00:18, State = { Text = Starting, Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = True, ExitCode = -2147450735, Urls = { http = http://localhost:57621 }, EnvironmentVariables = { ASPNETCORE_ENVIRONMENT = Development ASPNETCORE_URLS = http://localhost:57629 DOTNET_LAUNCH_PROFILE = http DOTNET_SYSTEM_CONSOLE_ALLOW_ANSI_COLOR_REDIRECTION = true LOGGING__CONSOLE__FORMATTERNAME = simple OTEL_DOTNET_EXPERIMENTAL_OTLP_EMIT_EVENT_LOG_ATTRIBUTES = true OTEL_DOTNET_EXPERIMENTAL_OTLP_EMIT_EXCEPTION_LOG_ATTRIBUTES = true OTEL_DOTNET_EXPERIMENTAL_OTLP_RETRY = in_memory OTEL_EXPORTER_OTLP_ENDPOINT = http://localhost:18889 OTEL_EXPORTER_OTLP_PROTOCOL = grpc OTEL_RESOURCE_ATTRIBUTES = service.instance.id=msjmqgkd OTEL_SERVICE_NAME = force-resource-start-serviceb }, Properties = { executable.path = dotnet executable.workDir = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceB executable.args = run, --no-build, --project, D:\a\aspire\aspire\tests\testproject\TestProject.ServiceB\TestProject.ServiceB.csproj, -c, Debug, --no-launch-profile executable.pid = 0 project.path = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceB\TestProject.ServiceB.csproj resource.appArgs =  resource.appArgsSensitivity =  }, HealthReports = {  }, Commands = { resource-start (Start) = Disabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:00:19] Aspire.Hosting.ApplicationModel.ResourceNotificationService Debug: Waiting for resource 'force-resource-start-serviceb' to match predicate.
| [2025-06-27T07:00:19] Aspire.Hosting.ApplicationModel.ResourceNotificationService Debug: Waiting for resource 'force-resource-start-serviceb' to match predicate.
| [2025-06-27T07:00:19] Aspire.Hosting.ApplicationModel.ResourceNotificationService Debug: Finished waiting for resource 'force-resource-start-serviceb'.
| [2025-06-27T07:00:19] Aspire.Hosting.Tests.Resources.force-resource-start-serviceb Error: 29: 2025-06-27T07:00:19.2148308Z Resource 'force-resource-start-servicea' has entered the 'Finished' state prematurely.
| [2025-06-27T07:00:19] Aspire.Hosting.Dcp.DcpExecutor Error: Failed to start resource force-resource-start-serviceb
| Aspire.Hosting.DistributedApplicationException: Resource 'force-resource-start-servicea' has entered the 'Finished' state prematurely.
|    at Aspire.Hosting.ApplicationModel.ResourceNotificationService.<>c__DisplayClass18_0.<<WaitUntilHealthyAsync>g__Core|1>d.MoveNext() in /_/src/Aspire.Hosting/ApplicationModel/ResourceNotificationService.cs:line 186
| --- End of stack trace from previous location ---
|    at Aspire.Hosting.ApplicationModel.ResourceNotificationService.WaitUntilHealthyAsync(IResource resource, IResource dependency, WaitBehavior waitBehavior, CancellationToken cancellationToken) in /_/src/Aspire.Hosting/ApplicationModel/ResourceNotificationService.cs:line 158
|    at Aspire.Hosting.ApplicationModel.ResourceNotificationService.WaitForDependenciesAsync(IResource resource, CancellationToken cancellationToken) in /_/src/Aspire.Hosting/ApplicationModel/ResourceNotificationService.cs:line 391
|    at Aspire.Hosting.Orchestrator.ApplicationOrchestrator.WaitForInBeforeResourceStartedEvent(BeforeResourceStartedEvent event, CancellationToken cancellationToken) in /_/src/Aspire.Hosting/Orchestrator/ApplicationOrchestrator.cs:line 99
|    at Aspire.Hosting.Eventing.DistributedApplicationEventing.<>c__DisplayClass4_0`1.<<Subscribe>b__0>d.MoveNext() in /_/src/Aspire.Hosting/Eventing/DistributedApplicationEventing.cs:line 82
| --- End of stack trace from previous location ---
|    at Aspire.Hosting.Eventing.DistributedApplicationEventing.PublishAsync[T](T event, EventDispatchBehavior dispatchBehavior, CancellationToken cancellationToken) in /_/src/Aspire.Hosting/Eventing/DistributedApplicationEventing.cs:line 69
|    at Aspire.Hosting.Orchestrator.ApplicationOrchestrator.OnResourceStarting(OnResourceStartingContext context) in /_/src/Aspire.Hosting/Orchestrator/ApplicationOrchestrator.cs:line 183
|    at Aspire.Hosting.Dcp.DcpExecutorEvents.PublishAsync[T](T context) in /_/src/Aspire.Hosting/Dcp/DcpExecutorEvents.cs:line 33
|    at Aspire.Hosting.Dcp.DcpExecutor.StartResourceAsync(IResourceReference resourceReference, CancellationToken cancellationToken) in /_/src/Aspire.Hosting/Dcp/DcpExecutor.cs:line 1593
| [2025-06-27T07:00:19] Aspire.Hosting.ApplicationModel.ResourceNotificationService Debug: Resource force-resource-start-serviceb/force-resource-start-serviceb-msjmqgkd-3977a2ce changed state: Starting -> FailedToStart
| [2025-06-27T07:00:19] Aspire.Hosting.ApplicationModel.ResourceNotificationService Trace: Version: 23 Resource force-resource-start-serviceb/force-resource-start-serviceb-msjmqgkd-3977a2ce update published: ResourceType = Project, CreationTimeStamp = 2025-06-27T07:00:18, State = { Text = FailedToStart, Style = (null) }, IsHidden = False, HeathStatus = (null), ResourceReady = True, ExitCode = -2147450735, Urls = { http = http://localhost:57621 }, EnvironmentVariables = { ASPNETCORE_ENVIRONMENT = Development ASPNETCORE_URLS = http://localhost:57629 DOTNET_LAUNCH_PROFILE = http DOTNET_SYSTEM_CONSOLE_ALLOW_ANSI_COLOR_REDIRECTION = true LOGGING__CONSOLE__FORMATTERNAME = simple OTEL_DOTNET_EXPERIMENTAL_OTLP_EMIT_EVENT_LOG_ATTRIBUTES = true OTEL_DOTNET_EXPERIMENTAL_OTLP_EMIT_EXCEPTION_LOG_ATTRIBUTES = true OTEL_DOTNET_EXPERIMENTAL_OTLP_RETRY = in_memory OTEL_EXPORTER_OTLP_ENDPOINT = http://localhost:18889 OTEL_EXPORTER_OTLP_PROTOCOL = grpc OTEL_RESOURCE_ATTRIBUTES = service.instance.id=msjmqgkd OTEL_SERVICE_NAME = force-resource-start-serviceb }, Properties = { executable.path = dotnet executable.workDir = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceB executable.args = run, --no-build, --project, D:\a\aspire\aspire\tests\testproject\TestProject.ServiceB\TestProject.ServiceB.csproj, -c, Debug, --no-launch-profile executable.pid = 0 project.path = D:\a\aspire\aspire\tests\testproject\TestProject.ServiceB\TestProject.ServiceB.csproj resource.appArgs =  resource.appArgsSensitivity =  }, HealthReports = {  }, Commands = { resource-start (Start) = Enabled resource-stop (Stop) = Hidden resource-restart (Restart) = Disabled }
| [2025-06-27T07:03:19] Aspire.Hosting.Dcp.DcpExecutor Debug: Cancellation received while watching Executable resources.
| [2025-06-27T07:03:19] Aspire.Hosting.Dcp.DcpExecutor Debug: Stopped watching Executable resources.
| [2025-06-27T07:03:19] Aspire.Hosting.Dcp.DcpExecutor Debug: Cancellation received while watching Container resources.
| [2025-06-27T07:03:19] Aspire.Hosting.Dcp.DcpExecutor Debug: Stopped watching Container resources.
| [2025-06-27T07:03:19] Aspire.Hosting.Dcp.DcpExecutor Debug: Cancellation received while watching Service resources.
| [2025-06-27T07:03:19] Aspire.Hosting.Dcp.DcpExecutor Debug: Stopped watching Service resources.
| [2025-06-27T07:03:19] Aspire.Hosting.Dcp.DcpExecutor Debug: Cancellation received while watching Endpoint resources.
| [2025-06-27T07:03:19] Aspire.Hosting.Dcp.DcpExecutor Debug: Stopped watching Endpoint resources.
| [2025-06-27T07:03:19] Aspire.Hosting.Dcp.dcp.start-apiserver.api-server.adminHttpHandler Information: API server changed status	{"OldStatus": "Running", "NewStatus": "CleaningResources"}
| [2025-06-27T07:03:20] Aspire.Hosting.Dcp.dcp.start-apiserver.api-server.adminHttpHandler Information: API server changed status	{"OldStatus": "CleanupComplete", "NewStatus": "Stopping"}
| [2025-06-27T07:03:20] Aspire.Hosting.Dcp.dcp.start-apiserver Information: Shutting down...
| [2025-06-27T07:03:20] Aspire.Hosting.Dcp.dcp.start-apiserver.dcp-host Information: Service DCP controller host terminated gracefully
| [2025-06-27T07:03:20] Aspire.Hosting.Dcp.dcp.start-apiserver Information: Shutdown complete.

Report for PRs and Rolling builds

Last 10 failures:

Run date	Test Name
Jun 27 5 AM	[windows] Aspire.Hosting.Tests.DistributedApplicationTests.StartResourceForcesStart
Jun 27 1 AM	[windows] Aspire.Hosting.Tests.DistributedApplicationTests.StartResourceForcesStart
Jun 26 8 PM	[windows] Aspire.Hosting.Tests.DistributedApplicationTests.StartResourceForcesStart
Jun 26 5 PM	[macos] Aspire.Hosting.Tests.DistributedApplicationTests.StartResourceForcesStart

Summary of the failures

Last 24 hrs	Last 7 days	Last 30 days
2	4	4

-- Updated on 6/27/2025 10:38:56 PM UTC

[danegsta]

I believe this is a real failure in #9977 and not flakey. A recent DCP change, when combined with this PR, was causing timeouts in certain tests. The underlying issue is that DCP was having unexpected timeouts in a DNS lookup on the Mac CI machine. The next DCP insertion refactored things such that we no longer do the problematic DNS lookup.

[radical]

This was hit on https://github.com/dotnet/aspire/actions/runs/15918825970 also.
EDIT: for #10075