Having trouble getting an Azure Storage Queue trigger function working in Aspire

[mip1983]

Hi all, I'm after some help get an Azure Function with a storage queue trigger working with Aspire.

I've got my AppHost with similar configuration to this:

var storage = builder.AddAzureStorage("storgeAccount");
var queueStorage = storage.AddQueues("queueStorage");

var myFunction = builder.AddAzureFunctionsProject<Projects.functions_MyQueueFunction>("QueueFunction")
	.WithReference("queueStorage")
	.WithExternalHttpEndpoints();

And a simple function

[Function("MyQueueFunction)]
public async Task Run([QueueTrigger("myqueue, Connection = "queueStorage")] QueueMessage message)
{
	// Code here
	this.logger.LogInformation($"{nameof(MeterDataReprocessingQueueHandler)} Queue trigger function called with message: {message.MessageText}");
}

But there's this never hits a breakpoint or picks up anything from the queue.

I can an MSAL error in the lgos on startup like this:

False MSAL 4.61.3.0 MSAL.NetCore .NET 8.0.13 Microsoft Windows 10.0.26100 [2025-04-07 09:41:08Z - 11f54f1d-d704-4cb4-bd08-b32b1e2f80e4] Exception type: Azure.Identity.CredentialUnavailableException
---> Inner Exception Details
Exception type: System.Threading.Tasks.TaskCanceledException
---> Inner Exception Details
Exception type: System.Threading.Tasks.TaskCanceledException
---> Inner Exception Details
Exception type: System.Threading.Tasks.TaskCanceledException
To see full exception details, enable PII Logging. See https://aka.ms/msal-net-logging
   at System.Threading.Tasks.TaskCompletionSourceWithCancellation`1.WaitWithCancellationAsync(CancellationToken cancellationToken)
   at System.Net.Http.HttpConnectionPool.SendWithVersionDetectionAndRetryAsync(HttpRequestMessage request, Boolean async, Boolean doRequestAuth, CancellationToken cancellationToken)
   at System.Net.Http.DiagnosticsHandler.SendAsyncCore(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken)
   at System.Net.Http.HttpClient.<SendAsync>g__Core|83_0(HttpRequestMessage request, HttpCompletionOption completionOption, CancellationTokenSource cts, Boolean disposeCts, CancellationTokenSource pendingRequestsCts, CancellationToken originalCancellationToken)

And a message like this in the console of the function app:

 Value cannot be null. (Parameter 'provider')

I can see things in the queue in the Azure portal and have tried adding my account to roles like 'Storage Queue Data Message Processor' etc. to no avail. I've enabled more verbose logs, but just seeing more MSAL type errors. I don't know what it wants?

[mip1983]

Here's a bit more from the log output in aspire. It doesn't look to me like it's trying VisualStudioCredential? :

[2025-04-07T12:35:08.010Z] DefaultAzureCredential.GetToken invoked. Scopes: [ https://storage.azure.com//.default ] ParentRequestId: 089b4cbb-53ea-4a87-a56c-4befd504b6be
[2025-04-07T12:35:08.010Z] Count of in-buffer invocations waiting to be drained out: 0
[2025-04-07T12:35:08.012Z] Draining invocations from language worker channel completed. Shutting down 'RpcFunctionInvocationDispatcher'
[2025-04-07T12:35:08.013Z] Stopping JobHost
[2025-04-07T12:35:08.015Z] Stopping ScriptHost instance '182d640a-17b5-4ea4-9261-f9cde835a6a1'.
[2025-04-07T12:35:08.018Z] Stopping the listener 'Microsoft.Azure.WebJobs.Extensions.Storage.Common.Listeners.QueueListener' for function 'MeterDataReprocessingQueueHandler'
[2025-04-07T12:35:08.019Z] EnvironmentCredential.GetToken invoked. Scopes: [ https://storage.azure.com//.default ] ParentRequestId: 089b4cbb-53ea-4a87-a56c-4befd504b6be
[2025-04-07T12:35:08.021Z] EnvironmentCredential.GetToken was unable to retrieve an access token. Scopes: [ https://storage.azure.com//.default ] ParentRequestId: 089b4cbb-53ea-4a87-a56c-4befd504b6be Exception: Azure.Identity.CredentialUnavailableException (0x80131500): EnvironmentCredential authentication unavailable. Environment variables are not fully configured. See the troubleshooting guide for more information. https://aka.ms/azsdk/net/identity/environmentcredential/troubleshoot
[2025-04-07T12:35:08.026Z] WorkloadIdentityCredential.GetToken invoked. Scopes: [ https://storage.azure.com//.default ] ParentRequestId: 089b4cbb-53ea-4a87-a56c-4befd504b6be
[2025-04-07T12:35:08.027Z] WorkloadIdentityCredential.GetToken was unable to retrieve an access token. Scopes: [ https://storage.azure.com//.default ] ParentRequestId: 089b4cbb-53ea-4a87-a56c-4befd504b6be Exception: Azure.Identity.CredentialUnavailableException (0x80131500): WorkloadIdentityCredential authentication unavailable. The workload options are not fully configured. See the troubleshooting guide for more information. https://aka.ms/azsdk/net/identity/workloadidentitycredential/troubleshoot
[2025-04-07T12:35:08.029Z] ManagedIdentityCredential.GetToken invoked. Scopes: [ https://storage.azure.com//.default ] ParentRequestId: 089b4cbb-53ea-4a87-a56c-4befd504b6be
[2025-04-07T12:35:08.061Z] False MSAL 4.61.3.0 MSAL.NetCore .NET 8.0.13 Microsoft Windows 10.0.26100 [2025-04-07 12:35:08Z - 64d690aa-a3b3-4515-8b3b-27bf7b8013bd] MSAL MSAL.NetCore with assembly version '4.61.3.0'. CorrelationId(64d690aa-a3b3-4515-8b3b-27bf7b8013bd)
[2025-04-07T12:35:08.068Z] False MSAL 4.61.3.0 MSAL.NetCore .NET 8.0.13 Microsoft Windows 10.0.26100 [2025-04-07 12:35:08Z - 64d690aa-a3b3-4515-8b3b-27bf7b8013bd] === AcquireTokenForClientParameters ===
[2025-04-07T12:35:08.068Z] SendX5C: False
[2025-04-07T12:35:08.069Z] ForceRefresh: False
[2025-04-07T12:35:08.069Z]
[2025-04-07T12:35:08.076Z] False MSAL 4.61.3.0 MSAL.NetCore .NET 8.0.13 Microsoft Windows 10.0.26100 [2025-04-07 12:35:08Z - 64d690aa-a3b3-4515-8b3b-27bf7b8013bd]
[2025-04-07T12:35:08.077Z] === Request Data ===
[2025-04-07T12:35:08.078Z] Authority Provided? - True
[2025-04-07T12:35:08.079Z] Scopes - https://storage.azure.com//.default
[2025-04-07T12:35:08.080Z] Extra Query Params Keys (space separated) -
[2025-04-07T12:35:08.080Z] ApiId - AcquireTokenForClient
[2025-04-07T12:35:08.081Z] IsConfidentialClient - True
[2025-04-07T12:35:08.082Z] SendX5C - False
[2025-04-07T12:35:08.083Z] LoginHint ? False
[2025-04-07T12:35:08.083Z] IsBrokerConfigured - False
[2025-04-07T12:35:08.084Z] HomeAccountId - False
[2025-04-07T12:35:08.085Z] CorrelationId - 64d690aa-a3b3-4515-8b3b-27bf7b8013bd
[2025-04-07T12:35:08.086Z] UserAssertion set: False
[2025-04-07T12:35:08.086Z] LongRunningOboCacheKey set: False
[2025-04-07T12:35:08.087Z] Region configured:
[2025-04-07T12:35:08.088Z]
[2025-04-07T12:35:08.089Z] False MSAL 4.61.3.0 MSAL.NetCore .NET 8.0.13 Microsoft Windows 10.0.26100 [2025-04-07 12:35:08Z - 64d690aa-a3b3-4515-8b3b-27bf7b8013bd] === Token Acquisition (ClientCredentialRequest) started:
Scopes: https://storage.azure.com//.default
Authority Host: login.microsoftonline.com
[2025-04-07T12:35:08.101Z] False MSAL 4.61.3.0 MSAL.NetCore .NET 8.0.13 Microsoft Windows 10.0.26100 [2025-04-07 12:35:08Z - 64d690aa-a3b3-4515-8b3b-27bf7b8013bd] [Region discovery] Not using a regional authority.
[2025-04-07T12:35:08.102Z] False MSAL 4.61.3.0 MSAL.NetCore .NET 8.0.13 Microsoft Windows 10.0.26100 [2025-04-07 12:35:08Z - 64d690aa-a3b3-4515-8b3b-27bf7b8013bd] [Instance Discovery] Skipping Instance discovery because it is disabled.
[2025-04-07T12:35:08.114Z] False MSAL 4.61.3.0 MSAL.NetCore .NET 8.0.13 Microsoft Windows 10.0.26100 [2025-04-07 12:35:08Z - 64d690aa-a3b3-4515-8b3b-27bf7b8013bd] Exception type: System.Threading.Tasks.TaskCanceledException
[2025-04-07T12:35:08.115Z] To see full exception details, enable PII Logging. See https://aka.ms/msal-net-logging
[2025-04-07T12:35:08.116Z] at Microsoft.Identity.Client.Internal.Requests.ClientCredentialRequest.GetAccessTokenAsync(CancellationToken cancellationToken, ILoggerAdapter logger)
[2025-04-07T12:35:08.117Z] at Microsoft.Identity.Client.Internal.Requests.ClientCredentialRequest.ExecuteAsync(CancellationToken cancellationToken)
[2025-04-07T12:35:08.117Z] at Microsoft.Identity.Client.Internal.Requests.RequestBase.<>c__DisplayClass11_1.<b__1>d.MoveNext()
[2025-04-07T12:35:08.118Z] --- End of stack trace from previous location ---
[2025-04-07T12:35:08.119Z] at Microsoft.Identity.Client.Utils.StopwatchService.MeasureCodeBlockAsync(Func`1 codeBlock)
[2025-04-07T12:35:08.119Z] at Microsoft.Identity.Client.Internal.Requests.RequestBase.RunAsync(CancellationToken cancellationToken)
[2025-04-07T12:35:08.120Z]
[2025-04-07T12:35:08.123Z] ManagedIdentityCredential.GetToken was unable to retrieve an access token. Scopes: [ https://storage.azure.com//.default ] ParentRequestId: 089b4cbb-53ea-4a87-a56c-4befd504b6be Exception: System.Threading.Tasks.TaskCanceledException (0x8013153b): A task was canceled.
[2025-04-07T12:35:08.127Z] DefaultAzureCredential.GetToken was unable to retrieve an access token. Scopes: [ https://storage.azure.com//.default ] ParentRequestId: 089b4cbb-53ea-4a87-a56c-4befd504b6be Exception: System.Threading.Tasks.TaskCanceledException (0x8013153b): A task was canceled.
[2025-04-07T12:35:08.138Z] Storage queue listener stopped (queue name='meter-data-reprocessing-queue', storage account name='ecodriverstoragedev')
[2025-04-07T12:35:08.140Z] Stopped the listener 'Microsoft.Azure.WebJobs.Extensions.Storage.Common.Listeners.QueueListener' for function 'MeterDataReprocessingQueueHandler'
[2025-04-07T12:35:08.144Z] Job host stopped
[2025-04-07T12:35:08.145Z] Stopped ScriptHost instance '182d640a-17b5-4ea4-9261-f9cde835a6a1'.
[2025-04-07T12:35:08.150Z] Subscribed to diagnostic source 'Microsoft.Azure.Functions.Host.OpenTelemetry'
[2025-04-07T12:35:08.151Z] Diagnostic source 'Microsoft.Azure.Functions.Host.OpenTelemetry' emitted event 'OpenTelemetryEventListener':
[2025-04-07T12:35:08.152Z] OpenTelemetry MeterProvider was not found in application services. Metrics will remain disabled.
[2025-04-07T12:35:08.153Z]
[2025-04-07T12:35:08.154Z] Hosting stopped
[2025-04-07T12:35:08.155Z] Disposing ScriptHost.
[2025-04-07T12:35:08.157Z] DependencyTrackingTelemetryModule disposed.
[2025-04-07T12:35:08.158Z] Flushing OpenTelemetry.Trace.TracerProviderSdk

[mip1983]

I've got a bit further, I managed to eliminate the MSAL errors after quite a bit of trial and error with different roles, but the function itself was still shutting down without much clue as to why.

After a bit of a trial and error, I found if I comment out this line in 'AddServiceDefaults'

builder.ConfigureOpenTelemetry();

Things work. So there's something Azure functions with a queue trigger doesn't seem to like about open telemetry here.

This is the log output with open telemetry enabled:

[2025-04-08T09:13:17.861Z] Host state changed from Default to Initialized.
[2025-04-08T09:13:17.865Z] Storage queue listener started (queue name='meter-data-reprocessing-queue', storage account name='devstoreaccount1')
[2025-04-08T09:13:17.866Z] Host started (108ms)
[2025-04-08T09:13:17.867Z] Job host started
[2025-04-08T09:13:17.870Z] File event source initialized.
[2025-04-08T09:13:17.876Z] Debug file watch initialized.
[2025-04-08T09:13:17.877Z] Diagnostic file watch initialized.
[2025-04-08T09:13:17.877Z] Hosting started
[2025-04-08T09:13:17.878Z] Host state changed from Initialized to Running.
[2025-04-08T09:13:17.879Z] Startup operation '186c7f83-4b0a-41f5-bb02-702f5216b4e5' completed.
[2025-04-08T09:13:17.887Z] Host state changed from Running to Stopping.
[2025-04-08T09:13:17.888Z] Stopping host...
[2025-04-08T09:13:17.889Z] Active host changing from '8262e2b0-1c07-456b-bf5a-ee02e5a134fb' to '(null)'.
[2025-04-08T09:13:17.891Z] Hosting stopping
[2025-04-08T09:13:17.892Z] Request [47c9b57a-0207-4f4e-b1ac-b6d0dbaa00ef] GET http://127.0.0.1:65350/devstoreaccount1/meter-data-reprocessing-queue?comp=metadata
[2025-04-08T09:13:17.892Z] Stopping file watchers.
[2025-04-08T09:13:17.892Z] x-ms-version:2024-08-04
[2025-04-08T09:13:17.893Z] Accept:application/xml
[2025-04-08T09:13:17.894Z] x-ms-client-request-id:47c9b57a-0207-4f4e-b1ac-b6d0dbaa00ef
[2025-04-08T09:13:17.894Z] x-ms-return-client-request-id:true
[2025-04-08T09:13:17.895Z] Waiting for RpcFunctionInvocationDispatcher to shutdown
[2025-04-08T09:13:17.895Z] User-Agent:azsdk-net-Storage.Queues/12.19.0 (.NET 8.0.13; Microsoft Windows 10.0.26100)
[2025-04-08T09:13:17.896Z] x-ms-date:Tue, 08 Apr 2025 09:13:17 GMT
[2025-04-08T09:13:17.897Z] Authorization:REDACTED
[2025-04-08T09:13:17.897Z] client assembly: Azure.Storage.Queues
[2025-04-08T09:13:17.898Z] Count of in-buffer invocations waiting to be drained out: 0
[2025-04-08T09:13:17.899Z] Draining invocations from language worker channel completed. Shutting down 'RpcFunctionInvocationDispatcher'
[2025-04-08T09:13:17.900Z] Stopping JobHost
[2025-04-08T09:13:17.901Z] Stopping ScriptHost instance '8262e2b0-1c07-456b-bf5a-ee02e5a134fb'.
[2025-04-08T09:13:17.904Z] Stopping the listener 'Microsoft.Azure.WebJobs.Extensions.Storage.Common.Listeners.QueueListener' for function 'MeterDataReprocessingQueueHandler'
[2025-04-08T09:13:17.921Z] Request [47c9b57a-0207-4f4e-b1ac-b6d0dbaa00ef] exception System.Threading.Tasks.TaskCanceledException: The operation was canceled.
[2025-04-08T09:13:17.921Z] at Azure.Core.CancellationHelper.ThrowOperationCanceledException(Exception innerException, CancellationToken cancellationToken)
[2025-04-08T09:13:17.921Z] at Azure.Core.CancellationHelper.ThrowIfCancellationRequested(CancellationToken cancellationToken)
[2025-04-08T09:13:17.922Z] at Azure.Core.Pipeline.ResponseBodyPolicy.ThrowIfCancellationRequestedOrTimeout(CancellationToken originalToken, CancellationToken timeoutToken, Exception inner, TimeSpan timeout)
[2025-04-08T09:13:17.922Z] at Azure.Core.Pipeline.ResponseBodyPolicy.ProcessAsync(HttpMessage message, ReadOnlyMemory1 pipeline, Boolean async) [2025-04-08T09:13:17.923Z] at Azure.Core.Pipeline.LoggingPolicy.ProcessAsync(HttpMessage message, ReadOnlyMemory1 pipeline, Boolean async)
[2025-04-08T09:13:17.926Z] Storage queue listener stopped (queue name='meter-data-reprocessing-queue', storage account name='devstoreaccount1')
[2025-04-08T09:13:17.927Z] Stopped the listener 'Microsoft.Azure.WebJobs.Extensions.Storage.Common.Listeners.QueueListener' for function 'MeterDataReprocessingQueueHandler'
[2025-04-08T09:13:17.929Z] Job host stopped
[2025-04-08T09:13:17.929Z] Stopped ScriptHost instance '8262e2b0-1c07-456b-bf5a-ee02e5a134fb'.
[2025-04-08T09:13:17.932Z] Subscribed to diagnostic source 'Microsoft.Azure.Functions.Host.OpenTelemetry'
[2025-04-08T09:13:17.933Z] Diagnostic source 'Microsoft.Azure.Functions.Host.OpenTelemetry' emitted event 'OpenTelemetryEventListener':
[2025-04-08T09:13:17.933Z] OpenTelemetry MeterProvider was not found in application services. Metrics will remain disabled.
[2025-04-08T09:13:17.934Z]
[2025-04-08T09:13:17.934Z] Hosting stopped
[2025-04-08T09:13:17.935Z] Disposing ScriptHost.
[2025-04-08T09:13:17.936Z] DependencyTrackingTelemetryModule disposed.
[2025-04-08T09:13:17.937Z] Flushing OpenTelemetry.Trace.TracerProviderSdk ...
[2025-04-08T09:13:17.966Z] Request [262231bf-f624-4c22-b335-2a87326f3678] POST https://uksouth-1.in.applicationinsights.azure.com/v2.1/track
[2025-04-08T09:13:17.966Z] Content-Type:application/json
[2025-04-08T09:13:17.966Z] Accept:application/json
[2025-04-08T09:13:17.967Z] x-ms-client-request-id:262231bf-f624-4c22-b335-2a87326f3678
[2025-04-08T09:13:17.967Z] x-ms-return-client-request-id:true
[2025-04-08T09:13:17.967Z] User-Agent:azsdk-net-Monitor.OpenTelemetry.Exporter/1.3.0-beta.1 (.NET 8.0.13; Microsoft Windows 10.0.26100)
[2025-04-08T09:13:17.968Z] client assembly: Azure.Monitor.OpenTelemetry.Exporter
[2025-04-08T09:13:18.011Z] Response [262231bf-f624-4c22-b335-2a87326f3678] 200 OK (00.0s)
[2025-04-08T09:13:18.011Z] Transfer-Encoding:chunked
[2025-04-08T09:13:18.011Z] Server:Microsoft-HTTPAPI/2.0
[2025-04-08T09:13:18.012Z] Strict-Transport-Security:REDACTED
[2025-04-08T09:13:18.012Z] X-Content-Type-Options:REDACTED
[2025-04-08T09:13:18.013Z] Date:Tue, 08 Apr 2025 09:13:18 GMT
[2025-04-08T09:13:18.013Z] Content-Type:application/json; charset=utf-8
[2025-04-08T09:13:18.013Z]
[2025-04-08T09:13:18.015Z] Disposing OpenTelemetry.Logs.OpenTelemetryLoggerProvider ...
[2025-04-08T09:13:18.023Z] Request [95c38823-2908-4ce5-9f25-d554d4bd3c67] POST https://uksouth-1.in.applicationinsights.azure.com/v2.1/track
[2025-04-08T09:13:18.023Z] Content-Type:application/json
[2025-04-08T09:13:18.023Z] Accept:application/json
[2025-04-08T09:13:18.024Z] x-ms-client-request-id:95c38823-2908-4ce5-9f25-d554d4bd3c67
[2025-04-08T09:13:18.024Z] x-ms-return-client-request-id:true
[2025-04-08T09:13:18.024Z] User-Agent:azsdk-net-Monitor.OpenTelemetry.Exporter/1.3.0-beta.1 (.NET 8.0.13; Microsoft Windows 10.0.26100)
[2025-04-08T09:13:18.025Z] client assembly: Azure.Monitor.OpenTelemetry.Exporter
[2025-04-08T09:13:18.041Z] Response [95c38823-2908-4ce5-9f25-d554d4bd3c67] 200 OK (00.0s)
[2025-04-08T09:13:18.042Z] Transfer-Encoding:chunked
[2025-04-08T09:13:18.042Z] Server:Microsoft-HTTPAPI/2.0
[2025-04-08T09:13:18.042Z] Strict-Transport-Security:REDACTED
[2025-04-08T09:13:18.042Z] X-Content-Type-Options:REDACTED
[2025-04-08T09:13:18.043Z] Date:Tue, 08 Apr 2025 09:13:18 GMT
[2025-04-08T09:13:18.043Z] Content-Type:application/json; charset=utf-8
[2025-04-08T09:13:18.043Z]
[2025-04-08T09:13:18.046Z] Request [2b50af6f-6e49-4bfe-8ac0-20c0e4efc28b] POST https://westeurope-5.in.applicationinsights.azure.com/v2.1/track
[2025-04-08T09:13:18.047Z] Content-Type:application/json
[2025-04-08T09:13:18.047Z] Accept:application/json
[2025-04-08T09:13:18.047Z] x-ms-client-request-id:2b50af6f-6e49-4bfe-8ac0-20c0e4efc28b
[2025-04-08T09:13:18.048Z] x-ms-return-client-request-id:true
[2025-04-08T09:13:18.048Z] User-Agent:azsdk-net-Monitor.OpenTelemetry.Exporter/1.3.0-beta.1 (.NET 8.0.13; Microsoft Windows 10.0.26100)
[2025-04-08T09:13:18.048Z] client assembly: Azure.Monitor.OpenTelemetry.Exporter
[2025-04-08T09:13:18.060Z] Request [7efe1788-f152-4c39-9d2d-68934bca5d53] POST https://uksouth-1.in.applicationinsights.azure.com/v2.1/track
[2025-04-08T09:13:18.061Z] Content-Type:application/json
[2025-04-08T09:13:18.061Z] Accept:application/json
[2025-04-08T09:13:18.061Z] x-ms-client-request-id:7efe1788-f152-4c39-9d2d-68934bca5d53
[2025-04-08T09:13:18.062Z] x-ms-return-client-request-id:true
[2025-04-08T09:13:18.062Z] User-Agent:azsdk-net-Monitor.OpenTelemetry.Exporter/1.3.0-beta.1 (.NET 8.0.13; Microsoft Windows 10.0.26100)
[2025-04-08T09:13:18.062Z] client assembly: Azure.Monitor.OpenTelemetry.Exporter
[2025-04-08T09:13:18.086Z] Response [7efe1788-f152-4c39-9d2d-68934bca5d53] 200 OK (00.0s)
[2025-04-08T09:13:18.086Z] Transfer-Encoding:chunked
[2025-04-08T09:13:18.087Z] Server:Microsoft-HTTPAPI/2.0
[2025-04-08T09:13:18.087Z] Strict-Transport-Security:REDACTED
[2025-04-08T09:13:18.088Z] X-Content-Type-Options:REDACTED
[2025-04-08T09:13:18.088Z] Date:Tue, 08 Apr 2025 09:13:18 GMT
[2025-04-08T09:13:18.089Z] Content-Type:application/json; charset=utf-8
[2025-04-08T09:13:18.089Z]
[2025-04-08T09:13:18.116Z] Response [2b50af6f-6e49-4bfe-8ac0-20c0e4efc28b] 200 OK (00.1s)
[2025-04-08T09:13:18.117Z] Transfer-Encoding:chunked
[2025-04-08T09:13:18.117Z] Server:Microsoft-HTTPAPI/2.0
[2025-04-08T09:13:18.117Z] Strict-Transport-Security:REDACTED
[2025-04-08T09:13:18.118Z] X-Content-Type-Options:REDACTED
[2025-04-08T09:13:18.118Z] Date:Tue, 08 Apr 2025 09:13:18 GMT
[2025-04-08T09:13:18.119Z] Content-Type:application/json; charset=utf-8
[2025-04-08T09:13:18.119Z]
[2025-04-08T09:13:18.123Z] Disposing ScriptHost instance '8262e2b0-1c07-456b-bf5a-ee02e5a134fb'.
[2025-04-08T09:13:18.123Z] Disposing FunctionDispatcher
[2025-04-08T09:13:18.125Z] Disposed ScriptHost instance '8262e2b0-1c07-456b-bf5a-ee02e5a134fb'.
[2025-04-08T09:13:18.126Z] Disposing ScriptHost instance '8262e2b0-1c07-456b-bf5a-ee02e5a134fb'.
[2025-04-08T09:13:18.126Z] Disposed ScriptHost instance '8262e2b0-1c07-456b-bf5a-ee02e5a134fb'.
[2025-04-08T09:13:18.152Z] ScriptHost disposed
[2025-04-08T09:13:18.152Z] Host shutdown completed.
[2025-04-08T09:13:18.153Z] Host state changed from Stopping to Stopped.
[2025-04-08T09:13:18.155Z] Shutting down Rpc Channels Manager
[2025-04-08T09:13:18.156Z] Shutting down language worker channels for runtime:dotnet-isolated
[2025-04-08T09:13:18.156Z] Sending WorkerTerminate message with grace period of 5 seconds.
[2025-04-08T09:13:18.159Z] [channel] received 46985585-d288-4064-9e1e-3cdf5d3cdf78: None
[2025-04-08T09:13:18.513Z] Process 1532 has exited with code 0.
[2025-04-08T09:13:18.516Z] Exception on worker disposal.
[2025-04-08T09:13:18.516Z] System.Diagnostics.Process: No process is associated with this object.
[2025-04-08T09:13:18.517Z] Shutting down RPC server
[2025-04-08T09:13:18.526Z] PrimaryHostStateProvider is not available. Skipping the check for primary host.
[2025-04-08T09:13:18.568Z] Queueing background table purge.
[2025-04-08T09:13:18.571Z] Disposing MetricsEventManager
[2025-04-08T09:13:48.593Z] Deleting 0 old tables.
Value cannot be null. (Parameter 'provider')

[davidfowl]

@mip1983 could you file an issue with a repro so we can investigate? I can move this discussion to an issue if you'd like.

[mip1983]

Thanks @davidfowl , the MSAL messages turned out to be a bit of a red herring, the issue seems to be this port number issue which would result in the function stopping (then tasks would be cancelled and you'd get errors like the MSAL ones).

#8589 (comment)

Might be worth looking into is if it's possible to get something clearer in the logs if a function is stopping, giving a clearer reason, i.e. the port conflict? Took a bit of tracking down to work this one out. You see:

[2025-04-08T09:13:17.887Z] Host state changed from Running to Stopping.
[2025-04-08T09:13:17.888Z] Stopping host...
[2025-04-08T09:13:17.889Z] Active host changing from '8262e2b0-1c07-456b-bf5a-ee02e5a134fb' to '(null)'.
[2025-04-08T09:13:17.891Z] Hosting stopping

But no why or warnings.