JavaScript function upgrade to v4 is failing - System.Threading.Tasks.TaskCanceledException : A task was canceled

[gautamsamal]

Investigative information

• Timestamp: 2022-12-21T08:56:23.576Z
• Function App version: Runtime version ~4 (NodeJS 14)
• Function App Name - Masked with a dummy name. Host id remains intact if that helps
• Region: West Europe

Repro steps

Unable to start the function app after upgrading runtime version from ~3 to ~4. We are using NodeJS 14 which is well supported in ~4. I have tried upgrading the NodeJS version to 16, that didn't help. Downgrading to ~3 works fine.

Expected behavior

The function app should run after upgrading runtime.

Actual behavior

Facing issue as "Function app is unreachable" after upgrading.
'exceptionMessage':'A task was canceled.','exceptionType':'System.Threading.Tasks.TaskCanceledException'

Related information

I have looked into the Docker logs to see the actual error. These are the logs that I found along with the app-settings. The function app is running on a Linux host in this case and has only Queue Trigger(s).

docker log

2022-12-21T08:56:23.576Z INFO  - Pulling image: mcr.microsoft.com/azure-functions/node:4-node14-appservice-stage4
2022-12-21T08:56:23.799Z INFO  - 4-node14-appservice-stage4 Pulling from azure-functions/node
2022-12-21T08:56:23.801Z INFO  -  Digest: sha256:115e4f354d69da6a4e84deef71baa6c42c022ec75d7cb175147a4d6111bf2bf8
2022-12-21T08:56:23.807Z INFO  -  Status: Image is up to date for mcr.microsoft.com/azure-functions/node:4-node14-appservice-stage4
2022-12-21T08:56:23.811Z INFO  - Pull Image successful, Time taken: 0 Minutes and 0 Seconds
2022-12-21T08:56:23.839Z INFO  - Starting container for site
2022-12-21T08:56:23.846Z INFO  - docker run -d --expose=80 --name test-function-app-1_1_e3d6c23b -e WEBSITES_ENABLE_APP_SERVICE_STORAGE=true -e WEBSITE_SITE_NAME=test-function-app-1 -e WEBSITE_AUTH_ENABLED=False -e PORT=80 -e WEBSITE_ROLE_INSTANCE_ID=0 -e WEBSITE_HOSTNAME=test-function-app-1.azurewebsites.net -e WEBSITE_INSTANCE_ID=8b490c4f50653849dca061a82470b861b1dbfc5ccd2f7d24a917d0acfe80cc05 -e WEBSITE_USE_DIAGNOSTIC_SERVER=True mcr.microsoft.com/azure-functions/node:4-node14-appservice-stage4  

2022-12-21T08:56:23.848Z INFO  - Logging is not enabled for this container.
Please use https://aka.ms/linux-diagnostics to enable logging to see container logs here.
2022-12-21T08:56:25.516Z INFO  - Initiating warmup request to container test-function-app-1_1_e3d6c23b_msiProxy for site test-function-app-1
2022-12-21T08:56:25.544Z INFO  - Container test-function-app-1_1_e3d6c23b_msiProxy for site test-function-app-1 initialized successfully and is ready to serve requests.
2022-12-21T08:56:25.547Z INFO  - Initiating warmup request to container test-function-app-1_1_e3d6c23b for site test-function-app-1
2022-12-21T08:56:35.730Z ERROR - Container test-function-app-1_1_e3d6c23b for site test-function-app-1 has exited, failing site start
2022-12-21T08:56:35.874Z ERROR - Container test-function-app-1_1_e3d6c23b didn't respond to HTTP pings on port: 80, failing site start. See container logs for debugging.
2022-12-21T08:56:35.881Z INFO  - Stopping site test-function-app-1 because it failed during startup.

Container log

2022-12-21T08:55:47.240826793Z Starting OpenBSD Secure Shell server: sshd.
2022-12-21T08:55:54.292463601Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'Initializing Warmup Extension.','category':'Host.Triggers.Warmup','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24}",12/21/2022 08:55:54
2022-12-21T08:55:54.501548584Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'Initializing Host. OperationId: 18234877-aebf-4462-bc4c-82ef2541b86f.','category':'Host.Startup','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24,'eventId':503,'eventName':'Initializing'}",12/21/2022 08:55:54
2022-12-21T08:55:54.539959898Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'Host initialization: ConsecutiveErrors=0, StartupCount=1, OperationId=18234877-aebf-4462-bc4c-82ef2541b86f','category':'Host.Startup','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24,'eventId':504,'eventName':'Initialization'}",12/21/2022 08:55:54
2022-12-21T08:55:54.680775014Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'ApplicationInsightsLoggerOptions\n{\n  \'SamplingSettings\': {\n    \'EvaluationInterval\': \'00:00:15\',\n    \'InitialSamplingPercentage\': 100.0,\n    \'MaxSamplingPercentage\': 100.0,\n    \'MaxTelemetryItemsPerSecond\': 20.0,\n    \'MinSamplingPercentage\': 0.1,\n    \'MovingAverageRatio\': 0.25,\n    \'SamplingPercentageDecreaseTimeout\': \'00:02:00\',\n    \'SamplingPercentageIncreaseTimeout\': \'00:15:00\'\n  },\n  \'SamplingExcludedTypes\': \'Request;Trace\',\n  \'SamplingIncludedTypes\': null,\n  \'SnapshotConfiguration\': null,\n  \'EnablePerformanceCountersCollection\': true,\n  \'HttpAutoCollectionOptions\': {\n    \'EnableHttpTriggerExtendedInfoCollection\': true,\n    \'EnableW3CDistributedTracing\': true,\n    \'EnableResponseHeaderInjection\': true\n  },\n  \'LiveMetricsInitializationDelay\': \'00:00:15\',\n  \'EnableLiveMetrics\': true,\n  \'EnableDependencyTracking\': true,\n  \'DependencyTrackingOptions\': null\n}','category':'Microsoft.Azure.WebJobs.Hosting.OptionsLoggingService','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24}",12/21/2022 08:55:54
2022-12-21T08:55:54.681064419Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'LoggerFilterOptions\n{\n  \'MinLevel\': \'None\',\n  \'Rules\': [\n    {\n      \'ProviderName\': null,\n      \'CategoryName\': null,\n      \'LogLevel\': null,\n      \'Filter\': \'<AddFilter>b__0\'\n    },\n    {\n      \'ProviderName\': \'Microsoft.Azure.WebJobs.Script.WebHost.Diagnostics.SystemLoggerProvider\',\n      \'CategoryName\': null,\n      \'LogLevel\': \'None\',\n      \'Filter\': null\n    },\n    {\n      \'ProviderName\': \'Microsoft.Azure.WebJobs.Script.WebHost.Diagnostics.SystemLoggerProvider\',\n      \'CategoryName\': null,\n      \'LogLevel\': null,\n      \'Filter\': \'<AddFilter>b__0\'\n    },\n    {\n      \'ProviderName\': \'Microsoft.Azure.WebJobs.Logging.ApplicationInsights.ApplicationInsightsLoggerProvider\',\n      \'CategoryName\': null,\n      \'LogLevel\': \'Trace\',\n      \'Filter\': null\n    }\n  ]\n}','category':'Microsoft.Azure.WebJobs.Hosting.OptionsLoggingService','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24}",12/21/2022 08:55:54
2022-12-21T08:55:54.681282423Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'LoggerFilterOptions\n{\n  \'MinLevel\': \'None\',\n  \'Rules\': [\n    {\n      \'ProviderName\': null,\n      \'CategoryName\': null,\n      \'LogLevel\': null,\n      \'Filter\': \'<AddFilter>b__0\'\n    },\n    {\n      \'ProviderName\': \'Microsoft.Azure.WebJobs.Script.WebHost.Diagnostics.SystemLoggerProvider\',\n      \'CategoryName\': null,\n      \'LogLevel\': \'None\',\n      \'Filter\': null\n    },\n    {\n      \'ProviderName\': \'Microsoft.Azure.WebJobs.Script.WebHost.Diagnostics.SystemLoggerProvider\',\n      \'CategoryName\': null,\n      \'LogLevel\': null,\n      \'Filter\': \'<AddFilter>b__0\'\n    },\n    {\n      \'ProviderName\': \'Microsoft.Azure.WebJobs.Logging.ApplicationInsights.ApplicationInsightsLoggerProvider\',\n      \'CategoryName\': null,\n      \'LogLevel\': \'Trace\',\n      \'Filter\': null\n    }\n  ]\n}','category':'Microsoft.Azure.WebJobs.Hosting.OptionsLoggingService','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24}",12/21/2022 08:55:54
2022-12-21T08:55:54.681366425Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'ConcurrencyOptions\n{\n  \'DynamicConcurrencyEnabled\': false,\n  \'MaximumFunctionConcurrency\': 500,\n  \'CPUThreshold\': 0.8,\n  \'SnapshotPersistenceEnabled\': true\n}','category':'Microsoft.Azure.WebJobs.Hosting.OptionsLoggingService','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24}",12/21/2022 08:55:54
2022-12-21T08:55:54.681508827Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'FunctionResultAggregatorOptions\n{\n  \'BatchSize\': 1000,\n  \'FlushTimeout\': \'00:00:30\',\n  \'IsEnabled\': true\n}','category':'Microsoft.Azure.WebJobs.Hosting.OptionsLoggingService','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24}",12/21/2022 08:55:54
2022-12-21T08:55:54.681606129Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'SingletonOptions\n{\n  \'LockPeriod\': \'00:00:15\',\n  \'ListenerLockPeriod\': \'00:01:00\',\n  \'LockAcquisitionTimeout\': \'10675199.02:48:05.4775807\',\n  \'LockAcquisitionPollingInterval\': \'00:00:05\',\n  \'ListenerLockRecoveryPollingInterval\': \'00:01:00\'\n}','category':'Microsoft.Azure.WebJobs.Hosting.OptionsLoggingService','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24}",12/21/2022 08:55:54
2022-12-21T08:55:54.681734131Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'QueuesOptions\n{\n  \'BatchSize\': 16,\n  \'NewBatchThreshold\': 16,\n  \'MaxPollingInterval\': \'00:01:00\',\n  \'MaxDequeueCount\': 5,\n  \'VisibilityTimeout\': \'00:00:00\'\n}','category':'Microsoft.Azure.WebJobs.Hosting.OptionsLoggingService','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24}",12/21/2022 08:55:54
2022-12-21T08:55:54.681869334Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'BlobsOptions\n{\n  \'CentralizedPoisonQueue\': false\n}','category':'Microsoft.Azure.WebJobs.Hosting.OptionsLoggingService','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24}",12/21/2022 08:55:54
2022-12-21T08:55:54.687093731Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'Starting JobHost','category':'Microsoft.Azure.WebJobs.Hosting.JobHostService','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24}",12/21/2022 08:55:54
2022-12-21T08:55:54.687113731Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'Starting Host (HostId=test-function-app-1, InstanceId=1341689b-a938-4096-b67e-14d97421d5b3, Version=4.14.0.0, ProcessId=24, AppDomainId=1, InDebugMode=True, InDiagnosticMode=False, FunctionsExtensionVersion=~4)','category':'Host.Startup','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24,'eventId':401,'eventName':'StartingHost'}",12/21/2022 08:55:54
2022-12-21T08:55:54.726992972Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'Loading functions metadata','category':'Host.Startup','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24,'eventId':314,'eventName':'FunctionMetadataManagerLoadingFunctionsMetadata'}",12/21/2022 08:55:54
2022-12-21T08:55:54.793138501Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'Reading functions metadata','category':'Host.Startup','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24,'eventId':326,'eventName':'FunctionMetadataManagerLoadingFunctionsMetadata'}",12/21/2022 08:55:54
2022-12-21T08:55:54.828995767Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'1 functions found','category':'Host.Startup','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24,'eventId':327,'eventName':'FunctionMetadataManagerFunctionsLoaded'}",12/21/2022 08:55:54
2022-12-21T08:55:54.829576578Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'1 functions loaded','category':'Host.Startup','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24,'eventId':315,'eventName':'FunctionMetadataManagerFunctionsLoaded'}",12/21/2022 08:55:54
2022-12-21T08:55:54.887793659Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'Generating 1 job function(s)','category':'Host.Startup','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24}",12/21/2022 08:55:54
2022-12-21T08:55:55.823860655Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'Found the following functions:\nHost.Functions.NotificationServiceTrigger\n','category':'Host.Startup','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24}",12/21/2022 08:55:55
2022-12-21T08:55:55.845210852Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'Initializing function HTTP routes\nNo HTTP routes mapped\n','category':'Microsoft.Azure.WebJobs.Script.WebHost.WebScriptHostHttpRoutesManager','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24}",12/21/2022 08:55:55
2022-12-21T08:55:55.851907976Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'HttpOptions\n{\n  \'DynamicThrottlesEnabled\': false,\n  \'EnableChunkedRequestBinding\': false,\n  \'MaxConcurrentRequests\': -1,\n  \'MaxOutstandingRequests\': -1,\n  \'RoutePrefix\': \'api\'\n}','category':'Microsoft.Azure.WebJobs.Hosting.OptionsLoggingService','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24}",12/21/2022 08:55:55
2022-12-21T08:55:55.890611896Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'Host initialized (1162ms)','category':'Host.Startup','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24,'eventId':412,'eventName':'ScriptHostInitialized'}",12/21/2022 08:55:55
2022-12-21T08:55:55.910613468Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'Host started (1211ms)','category':'Host.Startup','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24,'eventId':413,'eventName':'ScriptHostStarted'}",12/21/2022 08:55:55
2022-12-21T08:55:55.910682669Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'Job host started','category':'Host.Startup','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24}",12/21/2022 08:55:55
2022-12-21T08:55:55.979053440Z Hosting environment: Production
2022-12-21T08:55:55.979509848Z Content root path: /azure-functions-host
2022-12-21T08:55:55.979527048Z Now listening on: http://[::]:80
2022-12-21T08:55:55.979711852Z Application started. Press Ctrl+C to shut down.
2022-12-21T08:55:56.362353467Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'Stopping JobHost','category':'Microsoft.Azure.WebJobs.Hosting.JobHostService','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24}",12/21/2022 08:55:56
2022-12-21T08:55:56.364707611Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'Stopping the listener Microsoft.Azure.WebJobs.Host.Queues.Listeners.QueueListener for function NotificationServiceTrigger','category':'Host.Startup','hostVersion':'4.14.0.0','functionName':'NotificationServiceTrigger','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24}",12/21/2022 08:55:56
2022-12-21T08:55:56.486929984Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'Stopped the listener Microsoft.Azure.WebJobs.Host.Queues.Listeners.QueueListener for function NotificationServiceTrigger','category':'Host.Startup','hostVersion':'4.14.0.0','functionName':'NotificationServiceTrigger','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24}",12/21/2022 08:55:56
2022-12-21T08:55:56.488522913Z MS_FUNCTION_AZURE_MONITOR_EVENT 4,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'Job host stopped','category':'Host.Startup','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Information','levelId':2,'processId':24}",12/21/2022 08:55:56
2022-12-21T08:55:56.605717493Z MS_FUNCTION_AZURE_MONITOR_EVENT 2,test-function-app-1.azurewebsites.net,Microsoft.Web/sites/functions/log,FunctionAppLogs,,"{'appName':'test-function-app-1','roleInstance':'411ef3490d6a8ea1b00e4474ff63d90893451b68bb8d43806018e4f18f5a4767','message':'Failed to start a new language worker for runtime: node.','category':'Microsoft.Azure.WebJobs.Script.Workers.Rpc.RpcFunctionInvocationDispatcher','hostVersion':'4.14.0.0','hostInstanceId':'1341689b-a938-4096-b67e-14d97421d5b3','level':'Error','levelId':4,'processId':24,'exceptionDetails':'System.Threading.Tasks.TaskCanceledException : A task was canceled.\n   at async Microsoft.Azure.WebJobs.Script.Grpc.GrpcWorkerChannel.StartWorkerProcessAsync(CancellationToken cancellationToken) at /src/azure-functions-host/src/WebJobs.Script.Grpc/Channel/GrpcWorkerChannel.cs : 271\n   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()\n   at async Microsoft.Azure.WebJobs.Script.Workers.Rpc.RpcFunctionInvocationDispatcher.InitializeJobhostLanguageWorkerChannelAsync(??) at /src/azure-functions-host/src/WebJobs.Script/Workers/Rpc/FunctionRegistration/RpcFunctionInvocationDispatcher.cs : 154\n   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()\n   at async Microsoft.Azure.WebJobs.Script.Workers.Rpc.RpcFunctionInvocationDispatcher.InitializeJobhostLanguageWorkerChannelAsync(??) at /src/azure-functions-host/src/WebJobs.Script/Workers/Rpc/FunctionRegistration/RpcFunctionInvocationDispatcher.cs : 146\n   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()\n   at async Microsoft.Azure.WebJobs.Script.Workers.Rpc.RpcFunctionInvocationDispatcher.InitializeJobhostLanguageWorkerChannelAsync(??) at /src/azure-functions-host/src/WebJobs.Script/Workers/Rpc/FunctionRegistration/RpcFunctionInvocationDispatcher.cs : 137\n   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()\n   at async Microsoft.Azure.WebJobs.Script.Workers.Rpc.RpcFunctionInvocationDispatcher.<>c__DisplayClass56_0.<StartWorkerProcesses>b__0(??) at /src/azure-functions-host/src/WebJobs.Script/Workers/Rpc/FunctionRegistration/RpcFunctionInvocationDispatcher.cs : 229','exceptionMessage':'A task was canceled.','exceptionType':'System.Threading.Tasks.TaskCanceledException'}",12/21/2022 08:55:56

application settings

[
  {
    "name": "FUNCTIONS_EXTENSION_VERSION",
    "value": "~4",
    "slotSetting": false
  },
  {
    "name": "FUNCTIONS_WORKER_RUNTIME",
    "value": "node",
    "slotSetting": false
  },
  {
    "name": "WEBSITE_NODE_DEFAULT_VERSION",
    "value": "~14",
    "slotSetting": false
  },
  {
    "name": "WEBSITE_RUN_FROM_PACKAGE",
    "value": "1",
    "slotSetting": false
  }
]

[jviau]

@gautamsamal is this still happening? There was some deployment issues that I believe have been resolved - unable to tell if this is covered by one of those or not without more information.

[gautamsamal]

@jviau I believe this to be resolved. I remember myself upgrading a version of appinsights package, then it ran smoothly.

Thus we can close this.