search

PowerShell / vscode-powershell

Provides PowerShell language and debugging support for Visual Studio Code
https://marketplace.visualstudio.com/items/ms-vscode.PowerShell
MIT License
1.69k stars 484 forks source link

Azure functions in powershell #3300

Closed ghost closed 3 years ago

ghost commented 3 years ago

Debugger will not attach when attempting to run Azure Functions locally. Debugging works with plain old powershell scripts.

SydneyhSmith commented 3 years ago

Thanks @avacoder could you please provide more detailed reproduction steps, as well as any appropriate GIFs or images that can describe what you are experiencing, as well as version details and it would be really helpful if you could also attach the logs--instructions for how to do that are here

HoLengZai commented 3 years ago

Hi @SydneyhSmith, I got a similar behaviour as @avacoder but I post it in vscode-azurefunctions, I don't know on which GitHub project i should post my issue. https://github.com/microsoft/vscode-azurefunctions/issues/2776

My project works normally in VSCode PowerShell project and I can debug without any issue, warning or whatever My issue is when I move my PowerShell code in an Azure Function PowerShell even when the Azure Function is performing nothing I got an error with the Debugger, breakpoints work sometimes and my program doesn't work as expected.

ghost commented 3 years ago

Hi Sydney,

PFA the three log files generated.

The output of the ‘code -v’ command is:

1.55.2

3c4e3df9e89829dce27b7b5c24508306b151f30d

x64

Ever since my system ran a Windows update yesterday, the console no longer displays Write-Host output…and I have a lot of Write-Host statements in the function

I am using the add-in ‘Azure Functions for Visual Studio Code’ version 1.3.0

My system is running Powershell version 7.1.3

Below is the output of the task host window when I run the function (F5):

Azure Functions Core Tools

Core Tools Version:       3.0.3442 Commit hash: 6bfab24b2743f8421475d996402c398d2fe4a9e0  (64-bit)

Function Runtime Version: 3.0.15417.0

[2021-04-21T14:25:34.404Z] Starting Rpc Initialization Service.

[2021-04-21T14:25:34.408Z] Initializing RpcServer

[2021-04-21T14:25:34.421Z] RpcServer initialized

[2021-04-21T14:25:34.424Z] Rpc Initialization Service started.

[2021-04-21T14:25:34.430Z] Startup operation 'da6bafa7-d3f6-477e-9f9a-de56b9f1787e' with parent id '(null)' created.

[2021-04-21T14:25:34.434Z] Startup operation 'da6bafa7-d3f6-477e-9f9a-de56b9f1787e' starting.

[2021-04-21T14:25:34.439Z] Building host: startup suppressed: 'False', configuration suppressed: 'False', startup operation id: 'da6bafa7-d3f6-477e-9f9a-de56b9f1787e'

[2021-04-21T14:25:34.501Z] Host configuration applied.

[2021-04-21T14:25:34.504Z] Reading host configuration file 'C:\Users\jt.pierce\OneDrive - Avanade\source\repos\IT-AutomationIntegration\UtilityFunctions\host.json'

[2021-04-21T14:25:34.506Z] Host configuration file read:

[2021-04-21T14:25:34.510Z] {

[2021-04-21T14:25:34.514Z]   "version": "2.0",

[2021-04-21T14:25:34.517Z]   "logging": {

[2021-04-21T14:25:34.518Z]     "logLevel": {

[2021-04-21T14:25:34.519Z]       "Functions.VerifyResourceGroupAssets": "Debug",

[2021-04-21T14:25:34.520Z]       "default": "Debug"

[2021-04-21T14:25:34.521Z]     },

[2021-04-21T14:25:34.523Z]     "console": {

[2021-04-21T14:25:34.526Z]       "isEnabled": "true"

[2021-04-21T14:25:34.528Z]     }

[2021-04-21T14:25:34.529Z]   },

[2021-04-21T14:25:34.533Z]   "extensionBundle": {

[2021-04-21T14:25:34.534Z]     "id": "Microsoft.Azure.Functions.ExtensionBundle",

[2021-04-21T14:25:34.536Z]     "version": "[1.*, 2.0.0)"

[2021-04-21T14:25:34.537Z]   }

[2021-04-21T14:25:34.538Z] }

[2021-04-21T14:25:34.565Z] Loading functions metadata

[2021-04-21T14:25:34.567Z] Workers Directory set to: C:\Program Files\Microsoft\Azure Functions Core Tools\workers

[2021-04-21T14:25:34.569Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\java\worker.config.json

[2021-04-21T14:25:34.614Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:34.616Z] FUNCTIONS_WORKER_RUNTIME set to powershell. Skipping WorkerConfig for language:java

[2021-04-21T14:25:34.617Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\node\worker.config.json

[2021-04-21T14:25:34.633Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:34.634Z] FUNCTIONS_WORKER_RUNTIME set to powershell. Skipping WorkerConfig for language:node

[2021-04-21T14:25:34.636Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\powershell\worker.config.json

[2021-04-21T14:25:34.650Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:34.652Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME_VERSION: ~7

[2021-04-21T14:25:34.661Z] Added WorkerConfig for language: powershell

[2021-04-21T14:25:34.666Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\python\worker.config.json

[2021-04-21T14:25:34.684Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:34.685Z] FUNCTIONS_WORKER_RUNTIME set to powershell. Skipping WorkerConfig for language:python

[2021-04-21T14:25:34.688Z] Reading functions metadata

[2021-04-21T14:25:34.719Z] 6 functions found

[2021-04-21T14:25:34.738Z] 6 functions loaded

[2021-04-21T14:25:34.747Z] Looking for extension bundle Microsoft.Azure.Functions.ExtensionBundle at C:\Users\jt.pierce\.azure-functions-core-tools\Functions\ExtensionBundles\Microsoft.Azure.Functions.ExtensionBundle

[2021-04-21T14:25:34.751Z] Found a matching extension bundle at C:\Users\jt.pierce\.azure-functions-core-tools\Functions\ExtensionBundles\Microsoft.Azure.Functions.ExtensionBundle\1.5.1

[2021-04-21T14:25:34.754Z] Fetching information on versions of extension bundle Microsoft.Azure.Functions.ExtensionBundle available on https://functionscdn.azureedge.net/public/ExtensionBundles/Microsoft.Azure.Functions.ExtensionBundle/index.json

[2021-04-21T14:25:35.026Z] Skipping bundle download since it already exists at path C:\Users\jt.pierce\.azure-functions-core-tools\Functions\ExtensionBundles\Microsoft.Azure.Functions.ExtensionBundle\1.5.1

[2021-04-21T14:25:35.033Z] Loading extension bundle from C:\Users\jt.pierce\.azure-functions-core-tools\Functions\ExtensionBundles\Microsoft.Azure.Functions.ExtensionBundle\1.5.1\bin

[2021-04-21T14:25:35.071Z] Host configuration applied.

[2021-04-21T14:25:35.078Z] Reading host configuration file 'C:\Users\jt.pierce\OneDrive - Avanade\source\repos\IT-AutomationIntegration\UtilityFunctions\host.json'  

[2021-04-21T14:25:35.080Z] Host configuration file read:

[2021-04-21T14:25:35.081Z] {

[2021-04-21T14:25:35.084Z]   "version": "2.0",

[2021-04-21T14:25:35.085Z]   "logging": {

[2021-04-21T14:25:35.086Z]     "logLevel": {

[2021-04-21T14:25:35.088Z]       "Functions.VerifyResourceGroupAssets": "Debug",

[2021-04-21T14:25:35.093Z]       "default": "Debug"

[2021-04-21T14:25:35.097Z]     },

[2021-04-21T14:25:35.099Z]     "console": {

[2021-04-21T14:25:35.100Z]       "isEnabled": "true"

[2021-04-21T14:25:35.101Z]     }

[2021-04-21T14:25:35.104Z]   },

[2021-04-21T14:25:35.105Z]   "extensionBundle": {

[2021-04-21T14:25:35.112Z]     "id": "Microsoft.Azure.Functions.ExtensionBundle",

[2021-04-21T14:25:35.119Z]     "version": "[1.*, 2.0.0)"

[2021-04-21T14:25:35.120Z]   }

[2021-04-21T14:25:35.124Z] }

[2021-04-21T14:25:35.518Z] Active host changing from '(null)' to '25d723f4-fb93-45b5-bf29-af1c74480fbe'.

[2021-04-21T14:25:35.727Z] Workers Directory set to: C:\Program Files\Microsoft\Azure Functions Core Tools\workers

[2021-04-21T14:25:35.730Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\java\worker.config.json

[2021-04-21T14:25:35.732Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:35.734Z] FUNCTIONS_WORKER_RUNTIME set to powershell. Skipping WorkerConfig for language:java

[2021-04-21T14:25:35.736Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\node\worker.config.json

[2021-04-21T14:25:35.740Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:35.742Z] FUNCTIONS_WORKER_RUNTIME set to powershell. Skipping WorkerConfig for language:node

[2021-04-21T14:25:35.744Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\powershell\worker.config.json

[2021-04-21T14:25:35.746Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:35.749Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME_VERSION: ~7

[2021-04-21T14:25:35.751Z] Added WorkerConfig for language: powershell

[2021-04-21T14:25:35.753Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\python\worker.config.json

[2021-04-21T14:25:35.757Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:35.759Z] FUNCTIONS_WORKER_RUNTIME set to powershell. Skipping WorkerConfig for language:python

[2021-04-21T14:25:36.044Z] Initializing Warmup Extension.

[2021-04-21T14:25:36.118Z] Initializing Host. OperationId: 'da6bafa7-d3f6-477e-9f9a-de56b9f1787e'.

[2021-04-21T14:25:36.146Z] Host initialization: ConsecutiveErrors=0, StartupCount=1, OperationId=da6bafa7-d3f6-477e-9f9a-de56b9f1787e

[2021-04-21T14:25:36.165Z] Startup operation 'da6bafa7-d3f6-477e-9f9a-de56b9f1787e' is starting host instance '25d723f4-fb93-45b5-bf29-af1c74480fbe'.

[2021-04-21T14:25:36.168Z] Hosting starting

[2021-04-21T14:25:36.217Z] LoggerFilterOptions

[2021-04-21T14:25:36.219Z] {

[2021-04-21T14:25:36.233Z]   "MinLevel": "None",

[2021-04-21T14:25:36.234Z]   "Rules": [

[2021-04-21T14:25:36.235Z]     {

[2021-04-21T14:25:36.244Z]       "ProviderName": null,

[2021-04-21T14:25:36.246Z]       "CategoryName": null,

[2021-04-21T14:25:36.247Z]       "LogLevel": null,

[2021-04-21T14:25:36.249Z]       "Filter": "<AddFilter>b__0"

[2021-04-21T14:25:36.251Z]     },

[2021-04-21T14:25:36.258Z]     {

[2021-04-21T14:25:36.263Z]       "ProviderName": null,

[2021-04-21T14:25:36.265Z]       "CategoryName": "Functions.VerifyResourceGroupAssets",

[2021-04-21T14:25:36.266Z]       "LogLevel": "Debug",

[2021-04-21T14:25:36.268Z]       "Filter": null

[2021-04-21T14:25:36.279Z]     },

[2021-04-21T14:25:36.281Z]     {

[2021-04-21T14:25:36.282Z]       "ProviderName": null,

[2021-04-21T14:25:36.284Z]       "CategoryName": null,

[2021-04-21T14:25:36.285Z]       "LogLevel": "Debug",

[2021-04-21T14:25:36.297Z]       "Filter": null

[2021-04-21T14:25:36.299Z]     },

[2021-04-21T14:25:36.301Z]     {

[2021-04-21T14:25:36.302Z]       "ProviderName": "Microsoft.Azure.WebJobs.Script.WebHost.Diagnostics.SystemLoggerProvider",

[2021-04-21T14:25:36.310Z]       "CategoryName": null,

[2021-04-21T14:25:36.313Z]       "LogLevel": "None",

[2021-04-21T14:25:36.315Z]       "Filter": null

[2021-04-21T14:25:36.317Z]     },

[2021-04-21T14:25:36.318Z]     {

[2021-04-21T14:25:36.327Z]       "ProviderName": "Microsoft.Azure.WebJobs.Script.WebHost.Diagnostics.SystemLoggerProvider",

[2021-04-21T14:25:36.330Z]       "CategoryName": null,

[2021-04-21T14:25:36.332Z]       "LogLevel": null,

[2021-04-21T14:25:36.347Z]       "Filter": "<AddFilter>b__0"

[2021-04-21T14:25:36.350Z]     },

[2021-04-21T14:25:36.358Z]     {

[2021-04-21T14:25:36.361Z]       "ProviderName": "Azure.Functions.Cli.Diagnostics.ColoredConsoleLoggerProvider",

[2021-04-21T14:25:36.364Z]       "CategoryName": null,

[2021-04-21T14:25:36.368Z]       "LogLevel": null,

[2021-04-21T14:25:36.378Z]       "Filter": "<AddFilter>b__0"

[2021-04-21T14:25:36.395Z]     }

[2021-04-21T14:25:36.399Z]   ]

[2021-04-21T14:25:36.410Z] }

[2021-04-21T14:25:36.418Z] LoggerFilterOptions

[2021-04-21T14:25:36.424Z] {

[2021-04-21T14:25:36.431Z]   "MinLevel": "None",

[2021-04-21T14:25:36.433Z]   "Rules": [

[2021-04-21T14:25:36.434Z]     {

[2021-04-21T14:25:36.436Z]       "ProviderName": null,

[2021-04-21T14:25:36.448Z]       "CategoryName": null,

[2021-04-21T14:25:36.451Z]       "LogLevel": null,

[2021-04-21T14:25:36.458Z]       "Filter": "<AddFilter>b__0"

[2021-04-21T14:25:36.466Z]     },

[2021-04-21T14:25:36.468Z]     {

[2021-04-21T14:25:36.478Z]       "ProviderName": null,

[2021-04-21T14:25:36.480Z]       "CategoryName": "Functions.VerifyResourceGroupAssets",

[2021-04-21T14:25:36.482Z]       "LogLevel": "Debug",

[2021-04-21T14:25:36.484Z]       "Filter": null

[2021-04-21T14:25:36.490Z]     },

[2021-04-21T14:25:36.496Z]     {

[2021-04-21T14:25:36.497Z]       "ProviderName": null,

[2021-04-21T14:25:36.499Z]       "CategoryName": null,

[2021-04-21T14:25:36.500Z]       "LogLevel": "Debug",

[2021-04-21T14:25:36.502Z]       "Filter": null

[2021-04-21T14:25:36.512Z]     },

[2021-04-21T14:25:36.514Z]     {

[2021-04-21T14:25:36.515Z]       "ProviderName": "Microsoft.Azure.WebJobs.Script.WebHost.Diagnostics.SystemLoggerProvider",

[2021-04-21T14:25:36.518Z]       "CategoryName": null,

[2021-04-21T14:25:36.521Z]       "LogLevel": "None",

[2021-04-21T14:25:36.530Z]       "Filter": null

[2021-04-21T14:25:36.532Z]     },

[2021-04-21T14:25:36.536Z]     {

[2021-04-21T14:25:36.548Z]       "ProviderName": "Microsoft.Azure.WebJobs.Script.WebHost.Diagnostics.SystemLoggerProvider",

[2021-04-21T14:25:36.550Z]       "CategoryName": null,

[2021-04-21T14:25:36.552Z]       "LogLevel": null,

[2021-04-21T14:25:36.564Z]       "Filter": "<AddFilter>b__0"

[2021-04-21T14:25:36.566Z]     },

[2021-04-21T14:25:36.567Z]     {

[2021-04-21T14:25:36.568Z]       "ProviderName": "Azure.Functions.Cli.Diagnostics.ColoredConsoleLoggerProvider",

[2021-04-21T14:25:36.581Z]       "CategoryName": null,

[2021-04-21T14:25:36.584Z]       "LogLevel": null,

[2021-04-21T14:25:36.585Z]       "Filter": "<AddFilter>b__0"

[2021-04-21T14:25:36.595Z]     }

[2021-04-21T14:25:36.596Z]   ]

[2021-04-21T14:25:36.598Z] }

[2021-04-21T14:25:36.601Z] FunctionResultAggregatorOptions

[2021-04-21T14:25:36.602Z] {

[2021-04-21T14:25:36.611Z]   "BatchSize": 1000,

[2021-04-21T14:25:36.613Z]   "FlushTimeout": "00:00:30",

[2021-04-21T14:25:36.615Z]   "IsEnabled": true

[2021-04-21T14:25:36.616Z] }

[2021-04-21T14:25:36.627Z] SingletonOptions

[2021-04-21T14:25:36.629Z] {

[2021-04-21T14:25:36.630Z]   "LockPeriod": "00:00:15",

[2021-04-21T14:25:36.632Z]   "ListenerLockPeriod": "00:00:15",

[2021-04-21T14:25:36.636Z]   "LockAcquisitionTimeout": "10675199.02:48:05.4775807",

[2021-04-21T14:25:36.646Z]   "LockAcquisitionPollingInterval": "00:00:05",

[2021-04-21T14:25:36.649Z]   "ListenerLockRecoveryPollingInterval": "00:01:00"

[2021-04-21T14:25:36.659Z] }

[2021-04-21T14:25:36.662Z] HttpOptions

[2021-04-21T14:25:36.664Z] {

[2021-04-21T14:25:36.665Z]   "DynamicThrottlesEnabled": false,

[2021-04-21T14:25:36.668Z]   "MaxConcurrentRequests": -1,

[2021-04-21T14:25:36.675Z]   "MaxOutstandingRequests": -1,

[2021-04-21T14:25:36.678Z]   "RoutePrefix": "api"

[2021-04-21T14:25:36.680Z] }

[2021-04-21T14:25:36.696Z] Starting JobHost

[2021-04-21T14:25:36.710Z] Starting Host (HostId=win8ffsquvonu-874168845, InstanceId=25d723f4-fb93-45b5-bf29-af1c74480fbe, Version=3.0.15417.0, ProcessId=23096, AppDomainId=1, InDebugMode=False, InDiagnosticMode=False, FunctionsExtensionVersion=(null))

[2021-04-21T14:25:36.757Z] Loading functions metadata

[2021-04-21T14:25:36.764Z] Workers Directory set to: C:\Program Files\Microsoft\Azure Functions Core Tools\workers

[2021-04-21T14:25:36.767Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\java\worker.config.json

[2021-04-21T14:25:36.771Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:36.777Z] FUNCTIONS_WORKER_RUNTIME set to powershell. Skipping WorkerConfig for language:java

[2021-04-21T14:25:36.783Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\node\worker.config.json

[2021-04-21T14:25:36.797Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:36.800Z] FUNCTIONS_WORKER_RUNTIME set to powershell. Skipping WorkerConfig for language:node

[2021-04-21T14:25:36.807Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\powershell\worker.config.json

[2021-04-21T14:25:36.823Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:36.830Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME_VERSION: ~7

[2021-04-21T14:25:36.833Z] Added WorkerConfig for language: powershell

[2021-04-21T14:25:36.866Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\python\worker.config.json

[2021-04-21T14:25:36.874Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:36.882Z] FUNCTIONS_WORKER_RUNTIME set to powershell. Skipping WorkerConfig for language:python

[2021-04-21T14:25:36.933Z] Loading proxies metadata

[2021-04-21T14:25:37.112Z] Initializing Azure Function proxies

[2021-04-21T14:25:37.529Z] 0 proxies loaded

[2021-04-21T14:25:37.550Z] 6 functions loaded

[2021-04-21T14:25:37.563Z] Adding Function descriptor provider for language powershell.

[2021-04-21T14:25:37.566Z] Creating function descriptors.

[2021-04-21T14:25:37.632Z] Function descriptors created.

[2021-04-21T14:25:37.648Z] Setting up FunctionInvocationBuffer for function:Get-DuplicateSubnets with functionId:ed017d8b-6ac2-44c7-9ef4-f415d0fc9ac0

[2021-04-21T14:25:37.650Z] Setting up FunctionInvocationBuffer for function:GetResourceGroupAuditHistory with functionId:2018c30b-d252-482b-9a4a-110799f6c8f7        

[2021-04-21T14:25:37.661Z] Setting up FunctionInvocationBuffer for function:NotifyWebhookExpiration with functionId:39568700-ae29-4429-b8f3-a3503da05add

[2021-04-21T14:25:37.663Z] Setting up FunctionInvocationBuffer for function:RenewServiceConnectionAppCredentials with functionId:84e570d4-8e7c-464f-a047-e42483569d38[2021-04-21T14:25:37.664Z] Setting up FunctionInvocationBuffer for function:ReportResourceGroupAssets with functionId:d8e17872-48b4-4d8f-8805-d0092174e049

[2021-04-21T14:25:37.666Z] Setting up FunctionInvocationBuffer for function:VerifyResourceGroupAssets with functionId:9764067f-8e8c-467d-9264-c9c0caf0cf37

[2021-04-21T14:25:37.679Z] Initiating Worker Process start up

[2021-04-21T14:25:37.693Z] Starting worker process with FileName:C:\Program Files\dotnet\dotnet.exe WorkingDirectory:C:\Users\jt.pierce\OneDrive - Avanade\source\repos\IT-AutomationIntegration\UtilityFunctions Arguments: "C:\Program Files\Microsoft\Azure Functions Core Tools\workers\powershell\7/Microsoft.Azure.Functions.PowerShellWorker.dll" --host 127.0.0.1 --port 4994 --workerId 4f20f126-b761-4ef7-8d68-878d04549d66 --requestId 1f2c9490-4390-4253-85f0-aba4398dc00d --grpcMaxMessageLength 2147483647

[2021-04-21T14:25:37.980Z] C:\Program Files\dotnet\dotnet.exe process with Id=22504 started

[2021-04-21T14:25:37.987Z] Generating 6 job function(s)

[2021-04-21T14:25:38.061Z] Found the following functions:

[2021-04-21T14:25:38.074Z] Host.Functions.Get-DuplicateSubnets

[2021-04-21T14:25:38.078Z] Host.Functions.GetResourceGroupAuditHistory

[2021-04-21T14:25:38.080Z] Host.Functions.NotifyWebhookExpiration

[2021-04-21T14:25:38.082Z] Host.Functions.RenewServiceConnectionAppCredentials

[2021-04-21T14:25:38.131Z] Host.Functions.ReportResourceGroupAssets

[2021-04-21T14:25:38.163Z] Host.Functions.VerifyResourceGroupAssets

[2021-04-21T14:25:38.166Z] 

[2021-04-21T14:25:38.197Z] Initializing function HTTP routes

[2021-04-21T14:25:38.209Z] Mapped function route 'api/Get-DuplicateSubnets' [get] to 'Get-DuplicateSubnets'

[2021-04-21T14:25:38.211Z] Mapped function route 'api/GetResourceGroupAuditHistory' [get] to 'GetResourceGroupAuditHistory'

[2021-04-21T14:25:38.212Z] Mapped function route 'api/RenewServiceConnectionAppCredentials' [get,post] to 'RenewServiceConnectionAppCredentials'

[2021-04-21T14:25:38.214Z] Mapped function route 'api/ReportResourceGroupAssets' [get] to 'ReportResourceGroupAssets'

[2021-04-21T14:25:38.218Z] Mapped function route 'api/VerifyResourceGroupAssets' [get] to 'VerifyResourceGroupAssets'

[2021-04-21T14:25:38.225Z] 

[2021-04-21T14:25:38.248Z] Host initialized (1515ms)

[2021-04-21T14:25:38.262Z] Singleton lock acquired (win8ffsquvonu-874168845/Host.Functions.NotifyWebhookExpiration.Listener)

[2021-04-21T14:25:38.307Z] The 'NotifyWebhookExpiration' timer is using the schedule 'Cron: '0 0 0 * * *'' and the local time zone: '(UTC-05:00) Eastern Time (US & Canada)'

[2021-04-21T14:25:38.395Z] The listener for function 'Functions.NotifyWebhookExpiration' was unable to start.

[2021-04-21T14:25:38.407Z] The listener for function 'Functions.NotifyWebhookExpiration' was unable to start. Microsoft.Azure.Storage.Common: No valid combination of account information found.

[2021-04-21T14:25:38.465Z] Host started (1752ms)

[2021-04-21T14:25:38.473Z] Job host started

[2021-04-21T14:25:38.481Z] File event source initialized.

[2021-04-21T14:25:38.496Z] Debug file watch initialized.

[2021-04-21T14:25:38.499Z] Diagnostic file watch initialized.

[2021-04-21T14:25:38.511Z] Hosting started

[2021-04-21T14:25:38.526Z] Startup operation 'da6bafa7-d3f6-477e-9f9a-de56b9f1787e' completed.

Functions:

        Get-DuplicateSubnets: [GET] http://localhost:7071/api/Get-DuplicateSubnets

        GetResourceGroupAuditHistory: [GET] http://localhost:7071/api/GetResourceGroupAuditHistory

        RenewServiceConnectionAppCredentials: [GET,POST] http://localhost:7071/api/RenewServiceConnectionAppCredentials

        ReportResourceGroupAssets: [GET] http://localhost:7071/api/ReportResourceGroupAssets

        VerifyResourceGroupAssets: [GET] http://localhost:7071/api/VerifyResourceGroupAssets

        NotifyWebhookExpiration: timerTrigger

For detailed output, run func with --verbose flag.

[2021-04-21T14:25:38.941Z] System Log: { Log-Level: Information; Log-Message: Azure Functions PowerShell Worker version [3.0.629.0]. }

[2021-04-21T14:25:40.279Z] System Log: { Log-Level: Information; Log-Message: PowerShell version: '7.0.3'. }

[2021-04-21T14:25:40.487Z] Retrying to start listener for function 'Functions.NotifyWebhookExpiration' (Attempt 1)

[2021-04-21T14:25:40.490Z] Unable to acquire Singleton lock (win8ffsquvonu-874168845/Host.Functions.NotifyWebhookExpiration.Listener).

[2021-04-21T14:25:40.492Z] Listener successfully started for function 'Functions.NotifyWebhookExpiration' after 1 retries.

[2021-04-21T14:25:40.667Z] System Log: { Log-Level: Information; Log-Message: The enforced concurrency level (pool size limit) is '10'. }

[2021-04-21T14:25:40.698Z] Established RPC channel. WorkerId: 4f20f126-b761-4ef7-8d68-878d04549d66

[2021-04-21T14:25:40.702Z] Worker Process started. Received StartStream message

[2021-04-21T14:25:40.760Z] System Log: { Log-Level: Trace; Log-Message: Custom pipe name specified. You can attach to the process by using vscode or by running `Enter-PSHostProcess -CustomPipeName AzureFunctionsPSWorker` }

[2021-04-21T14:25:40.804Z] Received WorkerInitResponse. Worker process initialized

[2021-04-21T14:25:40.822Z] Worker capabilities: { "RpcHttpBodyOnly": "true" }

[2021-04-21T14:25:40.825Z] Updating capabilities: { "RpcHttpBodyOnly": "true" }

[2021-04-21T14:25:40.831Z] Adding jobhost language worker channel for runtime: powershell. workerId:4f20f126-b761-4ef7-8d68-878d04549d66

[2021-04-21T14:25:40.843Z] Sending FunctionLoadRequest for function:Get-DuplicateSubnets with functionId:ed017d8b-6ac2-44c7-9ef4-f415d0fc9ac0

[2021-04-21T14:25:40.852Z] Adding dependency download request to powershell language worker

[2021-04-21T14:25:40.857Z] Sending FunctionLoadRequest for function:GetResourceGroupAuditHistory with functionId:2018c30b-d252-482b-9a4a-110799f6c8f7

[2021-04-21T14:25:40.859Z] Adding dependency download request to powershell language worker

[2021-04-21T14:25:40.877Z] Sending FunctionLoadRequest for function:NotifyWebhookExpiration with functionId:39568700-ae29-4429-b8f3-a3503da05add

[2021-04-21T14:25:40.892Z] Adding dependency download request to powershell language worker

[2021-04-21T14:25:40.894Z] Sending FunctionLoadRequest for function:RenewServiceConnectionAppCredentials with functionId:84e570d4-8e7c-464f-a047-e42483569d38        

[2021-04-21T14:25:40.900Z] Adding dependency download request to powershell language worker

[2021-04-21T14:25:40.907Z] Sending FunctionLoadRequest for function:ReportResourceGroupAssets with functionId:d8e17872-48b4-4d8f-8805-d0092174e049

[2021-04-21T14:25:40.909Z] Adding dependency download request to powershell language worker

[2021-04-21T14:25:40.914Z] Sending FunctionLoadRequest for function:VerifyResourceGroupAssets with functionId:9764067f-8e8c-467d-9264-c9c0caf0cf37

[2021-04-21T14:25:40.922Z] Adding dependency download request to powershell language worker

[2021-04-21T14:25:40.924Z] Worker process started and initialized.

[2021-04-21T14:25:40.975Z] System Log: { Log-Level: Trace; Log-Message: Updating dependencies folder heartbeat for 'C:\Users\jt.pierce\AppData\Local\AzureFunctions\UtilityFunctions\ManagedDependencies\210409171842709.r''. }

[2021-04-21T14:25:41.013Z] System Log: { Log-Level: Trace; Log-Message: First function load request completed in 134 ms. }

[2021-04-21T14:25:41.022Z] System Log: { Log-Level: Trace; Log-Message: The function app has existing dependencies installed. Updating the dependencies to the latest versions will be performed in the background. New function app instances will pick up any new dependencies. }

[2021-04-21T14:25:41.143Z] Received FunctionLoadResponse for functionId:ed017d8b-6ac2-44c7-9ef4-f415d0fc9ac0

[2021-04-21T14:25:41.275Z] Received FunctionLoadResponse for functionId:2018c30b-d252-482b-9a4a-110799f6c8f7

[2021-04-21T14:25:41.283Z] Received FunctionLoadResponse for functionId:39568700-ae29-4429-b8f3-a3503da05add

[2021-04-21T14:25:41.372Z] Received FunctionLoadResponse for functionId:84e570d4-8e7c-464f-a047-e42483569d38

[2021-04-21T14:25:41.391Z] Received FunctionLoadResponse for functionId:d8e17872-48b4-4d8f-8805-d0092174e049

[2021-04-21T14:25:41.428Z] Received FunctionLoadResponse for functionId:9764067f-8e8c-467d-9264-c9c0caf0cf37

[2021-04-21T14:25:43.479Z] Host lock lease acquired by instance ID '00000000000000000000000074D3F107'.

From: Sydney Smith @.> Sent: Tuesday, April 20, 2021 4:35 PM To: PowerShell/vscode-powershell @.> Cc: avacoder @.>; Mention @.> Subject: Re: [PowerShell/vscode-powershell] Azure functions in powershell (#3300)

Thanks @avacoder https://github.com/avacoder could you please provide more detailed reproduction steps, as well as any appropriate GIFs or images that can describe what you are experiencing, as well as version details and it would be really helpful if you could also attach the logs--instructions for how to do that are here https://github.com/PowerShell/vscode-powershell/blob/master/docs/troubleshooting.md#providing-information-about-your-environment

— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/PowerShell/vscode-powershell/issues/3300#issuecomment-823582435 , or unsubscribe https://github.com/notifications/unsubscribe-auth/ABGQI4XLXRXYIJFLNLCM47LTJXQNPANCNFSM43IN5KXA . https://github.com/notifications/beacon/ABGQI4TQBVRVY566PMZ7DJLTJXQNPA5CNFSM43IN5KXKYY3PNVWWK3TUL52HS4DFVREXG43VMVBW63LNMVXHJKTDN5WW2ZLOORPWSZGOGELN5YY.gif

rjmholt commented 3 years ago

Reformat of @avacoder's comment:

Hi Sydney,

PFA the three log files generated.

The output of the ‘code -v’ command is:

1.55.2

3c4e3df9e89829dce27b7b5c24508306b151f30d

x64

Ever since my system ran a Windows update yesterday, the console no longer displays Write-Host output…and I have a lot of Write-Host statements in the function

I am using the add-in ‘Azure Functions for Visual Studio Code’ version 1.3.0

My system is running Powershell version 7.1.3

Below is the output of the task host window when I run the function (F5):

Azure Functions Core Tools

Core Tools Version:       3.0.3442 Commit hash: 6bfab24b2743f8421475d996402c398d2fe4a9e0  (64-bit)

Function Runtime Version: 3.0.15417.0

[2021-04-21T14:25:34.404Z] Starting Rpc Initialization Service.

[2021-04-21T14:25:34.408Z] Initializing RpcServer

[2021-04-21T14:25:34.421Z] RpcServer initialized

[2021-04-21T14:25:34.424Z] Rpc Initialization Service started.

[2021-04-21T14:25:34.430Z] Startup operation 'da6bafa7-d3f6-477e-9f9a-de56b9f1787e' with parent id '(null)' created.

[2021-04-21T14:25:34.434Z] Startup operation 'da6bafa7-d3f6-477e-9f9a-de56b9f1787e' starting.

[2021-04-21T14:25:34.439Z] Building host: startup suppressed: 'False', configuration suppressed: 'False', startup operation id: 'da6bafa7-d3f6-477e-9f9a-de56b9f1787e'

[2021-04-21T14:25:34.501Z] Host configuration applied.

[2021-04-21T14:25:34.504Z] Reading host configuration file 'C:\Users\jt.pierce\OneDrive - Avanade\source\repos\IT-AutomationIntegration\UtilityFunctions\host.json'

[2021-04-21T14:25:34.506Z] Host configuration file read:

[2021-04-21T14:25:34.510Z] {

[2021-04-21T14:25:34.514Z]   "version": "2.0",

[2021-04-21T14:25:34.517Z]   "logging": {

[2021-04-21T14:25:34.518Z]     "logLevel": {

[2021-04-21T14:25:34.519Z]       "Functions.VerifyResourceGroupAssets": "Debug",

[2021-04-21T14:25:34.520Z]       "default": "Debug"

[2021-04-21T14:25:34.521Z]     },

[2021-04-21T14:25:34.523Z]     "console": {

[2021-04-21T14:25:34.526Z]       "isEnabled": "true"

[2021-04-21T14:25:34.528Z]     }

[2021-04-21T14:25:34.529Z]   },

[2021-04-21T14:25:34.533Z]   "extensionBundle": {

[2021-04-21T14:25:34.534Z]     "id": "Microsoft.Azure.Functions.ExtensionBundle",

[2021-04-21T14:25:34.536Z]     "version": "[1.*, 2.0.0)"

[2021-04-21T14:25:34.537Z]   }

[2021-04-21T14:25:34.538Z] }

[2021-04-21T14:25:34.565Z] Loading functions metadata

[2021-04-21T14:25:34.567Z] Workers Directory set to: C:\Program Files\Microsoft\Azure Functions Core Tools\workers

[2021-04-21T14:25:34.569Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\java\worker.config.json

[2021-04-21T14:25:34.614Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:34.616Z] FUNCTIONS_WORKER_RUNTIME set to powershell. Skipping WorkerConfig for language:java

[2021-04-21T14:25:34.617Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\node\worker.config.json

[2021-04-21T14:25:34.633Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:34.634Z] FUNCTIONS_WORKER_RUNTIME set to powershell. Skipping WorkerConfig for language:node

[2021-04-21T14:25:34.636Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\powershell\worker.config.json

[2021-04-21T14:25:34.650Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:34.652Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME_VERSION: ~7

[2021-04-21T14:25:34.661Z] Added WorkerConfig for language: powershell

[2021-04-21T14:25:34.666Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\python\worker.config.json

[2021-04-21T14:25:34.684Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:34.685Z] FUNCTIONS_WORKER_RUNTIME set to powershell. Skipping WorkerConfig for language:python

[2021-04-21T14:25:34.688Z] Reading functions metadata

[2021-04-21T14:25:34.719Z] 6 functions found

[2021-04-21T14:25:34.738Z] 6 functions loaded

[2021-04-21T14:25:34.747Z] Looking for extension bundle Microsoft.Azure.Functions.ExtensionBundle at C:\Users\jt.pierce\.azure-functions-core-tools\Functions\ExtensionBundles\Microsoft.Azure.Functions.ExtensionBundle

[2021-04-21T14:25:34.751Z] Found a matching extension bundle at C:\Users\jt.pierce\.azure-functions-core-tools\Functions\ExtensionBundles\Microsoft.Azure.Functions.ExtensionBundle\1.5.1

[2021-04-21T14:25:34.754Z] Fetching information on versions of extension bundle Microsoft.Azure.Functions.ExtensionBundle available on https://functionscdn.azureedge.net/public/ExtensionBundles/Microsoft.Azure.Functions.ExtensionBundle/index.json

[2021-04-21T14:25:35.026Z] Skipping bundle download since it already exists at path C:\Users\jt.pierce\.azure-functions-core-tools\Functions\ExtensionBundles\Microsoft.Azure.Functions.ExtensionBundle\1.5.1

[2021-04-21T14:25:35.033Z] Loading extension bundle from C:\Users\jt.pierce\.azure-functions-core-tools\Functions\ExtensionBundles\Microsoft.Azure.Functions.ExtensionBundle\1.5.1\bin

[2021-04-21T14:25:35.071Z] Host configuration applied.

[2021-04-21T14:25:35.078Z] Reading host configuration file 'C:\Users\jt.pierce\OneDrive - Avanade\source\repos\IT-AutomationIntegration\UtilityFunctions\host.json'

[2021-04-21T14:25:35.080Z] Host configuration file read:

[2021-04-21T14:25:35.081Z] {

[2021-04-21T14:25:35.084Z]   "version": "2.0",

[2021-04-21T14:25:35.085Z]   "logging": {

[2021-04-21T14:25:35.086Z]     "logLevel": {

[2021-04-21T14:25:35.088Z]       "Functions.VerifyResourceGroupAssets": "Debug",

[2021-04-21T14:25:35.093Z]       "default": "Debug"

[2021-04-21T14:25:35.097Z]     },

[2021-04-21T14:25:35.099Z]     "console": {

[2021-04-21T14:25:35.100Z]       "isEnabled": "true"

[2021-04-21T14:25:35.101Z]     }

[2021-04-21T14:25:35.104Z]   },

[2021-04-21T14:25:35.105Z]   "extensionBundle": {

[2021-04-21T14:25:35.112Z]     "id": "Microsoft.Azure.Functions.ExtensionBundle",

[2021-04-21T14:25:35.119Z]     "version": "[1.*, 2.0.0)"

[2021-04-21T14:25:35.120Z]   }

[2021-04-21T14:25:35.124Z] }

[2021-04-21T14:25:35.518Z] Active host changing from '(null)' to '25d723f4-fb93-45b5-bf29-af1c74480fbe'.

[2021-04-21T14:25:35.727Z] Workers Directory set to: C:\Program Files\Microsoft\Azure Functions Core Tools\workers

[2021-04-21T14:25:35.730Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\java\worker.config.json

[2021-04-21T14:25:35.732Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:35.734Z] FUNCTIONS_WORKER_RUNTIME set to powershell. Skipping WorkerConfig for language:java

[2021-04-21T14:25:35.736Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\node\worker.config.json

[2021-04-21T14:25:35.740Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:35.742Z] FUNCTIONS_WORKER_RUNTIME set to powershell. Skipping WorkerConfig for language:node

[2021-04-21T14:25:35.744Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\powershell\worker.config.json

[2021-04-21T14:25:35.746Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:35.749Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME_VERSION: ~7

[2021-04-21T14:25:35.751Z] Added WorkerConfig for language: powershell

[2021-04-21T14:25:35.753Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\python\worker.config.json

[2021-04-21T14:25:35.757Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:35.759Z] FUNCTIONS_WORKER_RUNTIME set to powershell. Skipping WorkerConfig for language:python

[2021-04-21T14:25:36.044Z] Initializing Warmup Extension.

[2021-04-21T14:25:36.118Z] Initializing Host. OperationId: 'da6bafa7-d3f6-477e-9f9a-de56b9f1787e'.

[2021-04-21T14:25:36.146Z] Host initialization: ConsecutiveErrors=0, StartupCount=1, OperationId=da6bafa7-d3f6-477e-9f9a-de56b9f1787e

[2021-04-21T14:25:36.165Z] Startup operation 'da6bafa7-d3f6-477e-9f9a-de56b9f1787e' is starting host instance '25d723f4-fb93-45b5-bf29-af1c74480fbe'.

[2021-04-21T14:25:36.168Z] Hosting starting

[2021-04-21T14:25:36.217Z] LoggerFilterOptions

[2021-04-21T14:25:36.219Z] {

[2021-04-21T14:25:36.233Z]   "MinLevel": "None",

[2021-04-21T14:25:36.234Z]   "Rules": [

[2021-04-21T14:25:36.235Z]     {

[2021-04-21T14:25:36.244Z]       "ProviderName": null,

[2021-04-21T14:25:36.246Z]       "CategoryName": null,

[2021-04-21T14:25:36.247Z]       "LogLevel": null,

[2021-04-21T14:25:36.249Z]       "Filter": "<AddFilter>b__0"

[2021-04-21T14:25:36.251Z]     },

[2021-04-21T14:25:36.258Z]     {

[2021-04-21T14:25:36.263Z]       "ProviderName": null,

[2021-04-21T14:25:36.265Z]       "CategoryName": "Functions.VerifyResourceGroupAssets",

[2021-04-21T14:25:36.266Z]       "LogLevel": "Debug",

[2021-04-21T14:25:36.268Z]       "Filter": null

[2021-04-21T14:25:36.279Z]     },

[2021-04-21T14:25:36.281Z]     {

[2021-04-21T14:25:36.282Z]       "ProviderName": null,

[2021-04-21T14:25:36.284Z]       "CategoryName": null,

[2021-04-21T14:25:36.285Z]       "LogLevel": "Debug",

[2021-04-21T14:25:36.297Z]       "Filter": null

[2021-04-21T14:25:36.299Z]     },

[2021-04-21T14:25:36.301Z]     {

[2021-04-21T14:25:36.302Z]       "ProviderName": "Microsoft.Azure.WebJobs.Script.WebHost.Diagnostics.SystemLoggerProvider",

[2021-04-21T14:25:36.310Z]       "CategoryName": null,

[2021-04-21T14:25:36.313Z]       "LogLevel": "None",

[2021-04-21T14:25:36.315Z]       "Filter": null

[2021-04-21T14:25:36.317Z]     },

[2021-04-21T14:25:36.318Z]     {

[2021-04-21T14:25:36.327Z]       "ProviderName": "Microsoft.Azure.WebJobs.Script.WebHost.Diagnostics.SystemLoggerProvider",

[2021-04-21T14:25:36.330Z]       "CategoryName": null,

[2021-04-21T14:25:36.332Z]       "LogLevel": null,

[2021-04-21T14:25:36.347Z]       "Filter": "<AddFilter>b__0"

[2021-04-21T14:25:36.350Z]     },

[2021-04-21T14:25:36.358Z]     {

[2021-04-21T14:25:36.361Z]       "ProviderName": "Azure.Functions.Cli.Diagnostics.ColoredConsoleLoggerProvider",

[2021-04-21T14:25:36.364Z]       "CategoryName": null,

[2021-04-21T14:25:36.368Z]       "LogLevel": null,

[2021-04-21T14:25:36.378Z]       "Filter": "<AddFilter>b__0"

[2021-04-21T14:25:36.395Z]     }

[2021-04-21T14:25:36.399Z]   ]

[2021-04-21T14:25:36.410Z] }

[2021-04-21T14:25:36.418Z] LoggerFilterOptions

[2021-04-21T14:25:36.424Z] {

[2021-04-21T14:25:36.431Z]   "MinLevel": "None",

[2021-04-21T14:25:36.433Z]   "Rules": [

[2021-04-21T14:25:36.434Z]     {

[2021-04-21T14:25:36.436Z]       "ProviderName": null,

[2021-04-21T14:25:36.448Z]       "CategoryName": null,

[2021-04-21T14:25:36.451Z]       "LogLevel": null,

[2021-04-21T14:25:36.458Z]       "Filter": "<AddFilter>b__0"

[2021-04-21T14:25:36.466Z]     },

[2021-04-21T14:25:36.468Z]     {

[2021-04-21T14:25:36.478Z]       "ProviderName": null,

[2021-04-21T14:25:36.480Z]       "CategoryName": "Functions.VerifyResourceGroupAssets",

[2021-04-21T14:25:36.482Z]       "LogLevel": "Debug",

[2021-04-21T14:25:36.484Z]       "Filter": null

[2021-04-21T14:25:36.490Z]     },

[2021-04-21T14:25:36.496Z]     {

[2021-04-21T14:25:36.497Z]       "ProviderName": null,

[2021-04-21T14:25:36.499Z]       "CategoryName": null,

[2021-04-21T14:25:36.500Z]       "LogLevel": "Debug",

[2021-04-21T14:25:36.502Z]       "Filter": null

[2021-04-21T14:25:36.512Z]     },

[2021-04-21T14:25:36.514Z]     {

[2021-04-21T14:25:36.515Z]       "ProviderName": "Microsoft.Azure.WebJobs.Script.WebHost.Diagnostics.SystemLoggerProvider",

[2021-04-21T14:25:36.518Z]       "CategoryName": null,

[2021-04-21T14:25:36.521Z]       "LogLevel": "None",

[2021-04-21T14:25:36.530Z]       "Filter": null

[2021-04-21T14:25:36.532Z]     },

[2021-04-21T14:25:36.536Z]     {

[2021-04-21T14:25:36.548Z]       "ProviderName": "Microsoft.Azure.WebJobs.Script.WebHost.Diagnostics.SystemLoggerProvider",

[2021-04-21T14:25:36.550Z]       "CategoryName": null,

[2021-04-21T14:25:36.552Z]       "LogLevel": null,

[2021-04-21T14:25:36.564Z]       "Filter": "<AddFilter>b__0"

[2021-04-21T14:25:36.566Z]     },

[2021-04-21T14:25:36.567Z]     {

[2021-04-21T14:25:36.568Z]       "ProviderName": "Azure.Functions.Cli.Diagnostics.ColoredConsoleLoggerProvider",

[2021-04-21T14:25:36.581Z]       "CategoryName": null,

[2021-04-21T14:25:36.584Z]       "LogLevel": null,

[2021-04-21T14:25:36.585Z]       "Filter": "<AddFilter>b__0"

[2021-04-21T14:25:36.595Z]     }

[2021-04-21T14:25:36.596Z]   ]

[2021-04-21T14:25:36.598Z] }

[2021-04-21T14:25:36.601Z] FunctionResultAggregatorOptions

[2021-04-21T14:25:36.602Z] {

[2021-04-21T14:25:36.611Z]   "BatchSize": 1000,

[2021-04-21T14:25:36.613Z]   "FlushTimeout": "00:00:30",

[2021-04-21T14:25:36.615Z]   "IsEnabled": true

[2021-04-21T14:25:36.616Z] }

[2021-04-21T14:25:36.627Z] SingletonOptions

[2021-04-21T14:25:36.629Z] {

[2021-04-21T14:25:36.630Z]   "LockPeriod": "00:00:15",

[2021-04-21T14:25:36.632Z]   "ListenerLockPeriod": "00:00:15",

[2021-04-21T14:25:36.636Z]   "LockAcquisitionTimeout": "10675199.02:48:05.4775807",

[2021-04-21T14:25:36.646Z]   "LockAcquisitionPollingInterval": "00:00:05",

[2021-04-21T14:25:36.649Z]   "ListenerLockRecoveryPollingInterval": "00:01:00"

[2021-04-21T14:25:36.659Z] }

[2021-04-21T14:25:36.662Z] HttpOptions

[2021-04-21T14:25:36.664Z] {

[2021-04-21T14:25:36.665Z]   "DynamicThrottlesEnabled": false,

[2021-04-21T14:25:36.668Z]   "MaxConcurrentRequests": -1,

[2021-04-21T14:25:36.675Z]   "MaxOutstandingRequests": -1,

[2021-04-21T14:25:36.678Z]   "RoutePrefix": "api"

[2021-04-21T14:25:36.680Z] }

[2021-04-21T14:25:36.696Z] Starting JobHost

[2021-04-21T14:25:36.710Z] Starting Host (HostId=win8ffsquvonu-874168845, InstanceId=25d723f4-fb93-45b5-bf29-af1c74480fbe, Version=3.0.15417.0, ProcessId=23096, AppDomainId=1, InDebugMode=False, InDiagnosticMode=False, FunctionsExtensionVersion=(null))

[2021-04-21T14:25:36.757Z] Loading functions metadata

[2021-04-21T14:25:36.764Z] Workers Directory set to: C:\Program Files\Microsoft\Azure Functions Core Tools\workers

[2021-04-21T14:25:36.767Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\java\worker.config.json

[2021-04-21T14:25:36.771Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:36.777Z] FUNCTIONS_WORKER_RUNTIME set to powershell. Skipping WorkerConfig for language:java

[2021-04-21T14:25:36.783Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\node\worker.config.json

[2021-04-21T14:25:36.797Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:36.800Z] FUNCTIONS_WORKER_RUNTIME set to powershell. Skipping WorkerConfig for language:node

[2021-04-21T14:25:36.807Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\powershell\worker.config.json

[2021-04-21T14:25:36.823Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:36.830Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME_VERSION: ~7

[2021-04-21T14:25:36.833Z] Added WorkerConfig for language: powershell

[2021-04-21T14:25:36.866Z] Found worker config: C:\Program Files\Microsoft\Azure Functions Core Tools\workers\python\worker.config.json

[2021-04-21T14:25:36.874Z] EnvironmentVariable FUNCTIONS_WORKER_RUNTIME: powershell

[2021-04-21T14:25:36.882Z] FUNCTIONS_WORKER_RUNTIME set to powershell. Skipping WorkerConfig for language:python

[2021-04-21T14:25:36.933Z] Loading proxies metadata

[2021-04-21T14:25:37.112Z] Initializing Azure Function proxies

[2021-04-21T14:25:37.529Z] 0 proxies loaded

[2021-04-21T14:25:37.550Z] 6 functions loaded

[2021-04-21T14:25:37.563Z] Adding Function descriptor provider for language powershell.

[2021-04-21T14:25:37.566Z] Creating function descriptors.

[2021-04-21T14:25:37.632Z] Function descriptors created.

[2021-04-21T14:25:37.648Z] Setting up FunctionInvocationBuffer for function:Get-DuplicateSubnets with functionId:ed017d8b-6ac2-44c7-9ef4-f415d0fc9ac0

[2021-04-21T14:25:37.650Z] Setting up FunctionInvocationBuffer for function:GetResourceGroupAuditHistory with functionId:2018c30b-d252-482b-9a4a-110799f6c8f7

[2021-04-21T14:25:37.661Z] Setting up FunctionInvocationBuffer for function:NotifyWebhookExpiration with functionId:39568700-ae29-4429-b8f3-a3503da05add

[2021-04-21T14:25:37.663Z] Setting up FunctionInvocationBuffer for function:RenewServiceConnectionAppCredentials with functionId:84e570d4-8e7c-464f-a047-e42483569d38[2021-04-21T14:25:37.664Z] Setting up FunctionInvocationBuffer for function:ReportResourceGroupAssets with functionId:d8e17872-48b4-4d8f-8805-d0092174e049

[2021-04-21T14:25:37.666Z] Setting up FunctionInvocationBuffer for function:VerifyResourceGroupAssets with functionId:9764067f-8e8c-467d-9264-c9c0caf0cf37

[2021-04-21T14:25:37.679Z] Initiating Worker Process start up

[2021-04-21T14:25:37.693Z] Starting worker process with FileName:C:\Program Files\dotnet\dotnet.exe WorkingDirectory:C:\Users\jt.pierce\OneDrive - Avanade\source\repos\IT-AutomationIntegration\UtilityFunctions Arguments: "C:\Program Files\Microsoft\Azure Functions Core Tools\workers\powershell\7/Microsoft.Azure.Functions.PowerShellWorker.dll" --host 127.0.0.1 --port 4994 --workerId 4f20f126-b761-4ef7-8d68-878d04549d66 --requestId 1f2c9490-4390-4253-85f0-aba4398dc00d --grpcMaxMessageLength 2147483647

[2021-04-21T14:25:37.980Z] C:\Program Files\dotnet\dotnet.exe process with Id=22504 started

[2021-04-21T14:25:37.987Z] Generating 6 job function(s)

[2021-04-21T14:25:38.061Z] Found the following functions:

[2021-04-21T14:25:38.074Z] Host.Functions.Get-DuplicateSubnets

[2021-04-21T14:25:38.078Z] Host.Functions.GetResourceGroupAuditHistory

[2021-04-21T14:25:38.080Z] Host.Functions.NotifyWebhookExpiration

[2021-04-21T14:25:38.082Z] Host.Functions.RenewServiceConnectionAppCredentials

[2021-04-21T14:25:38.131Z] Host.Functions.ReportResourceGroupAssets

[2021-04-21T14:25:38.163Z] Host.Functions.VerifyResourceGroupAssets

[2021-04-21T14:25:38.166Z]

[2021-04-21T14:25:38.197Z] Initializing function HTTP routes

[2021-04-21T14:25:38.209Z] Mapped function route 'api/Get-DuplicateSubnets' [get] to 'Get-DuplicateSubnets'

[2021-04-21T14:25:38.211Z] Mapped function route 'api/GetResourceGroupAuditHistory' [get] to 'GetResourceGroupAuditHistory'

[2021-04-21T14:25:38.212Z] Mapped function route 'api/RenewServiceConnectionAppCredentials' [get,post] to 'RenewServiceConnectionAppCredentials'

[2021-04-21T14:25:38.214Z] Mapped function route 'api/ReportResourceGroupAssets' [get] to 'ReportResourceGroupAssets'

[2021-04-21T14:25:38.218Z] Mapped function route 'api/VerifyResourceGroupAssets' [get] to 'VerifyResourceGroupAssets'

[2021-04-21T14:25:38.225Z]

[2021-04-21T14:25:38.248Z] Host initialized (1515ms)

[2021-04-21T14:25:38.262Z] Singleton lock acquired (win8ffsquvonu-874168845/Host.Functions.NotifyWebhookExpiration.Listener)

[2021-04-21T14:25:38.307Z] The 'NotifyWebhookExpiration' timer is using the schedule 'Cron: '0 0 0 * * *'' and the local time zone: '(UTC-05:00) Eastern Time (US & Canada)'

[2021-04-21T14:25:38.395Z] The listener for function 'Functions.NotifyWebhookExpiration' was unable to start.

[2021-04-21T14:25:38.407Z] The listener for function 'Functions.NotifyWebhookExpiration' was unable to start. Microsoft.Azure.Storage.Common: No valid combination of account information found.

[2021-04-21T14:25:38.465Z] Host started (1752ms)

[2021-04-21T14:25:38.473Z] Job host started

[2021-04-21T14:25:38.481Z] File event source initialized.

[2021-04-21T14:25:38.496Z] Debug file watch initialized.

[2021-04-21T14:25:38.499Z] Diagnostic file watch initialized.

[2021-04-21T14:25:38.511Z] Hosting started

[2021-04-21T14:25:38.526Z] Startup operation 'da6bafa7-d3f6-477e-9f9a-de56b9f1787e' completed.

Functions:

        Get-DuplicateSubnets: [GET] http://localhost:7071/api/Get-DuplicateSubnets

        GetResourceGroupAuditHistory: [GET] http://localhost:7071/api/GetResourceGroupAuditHistory

        RenewServiceConnectionAppCredentials: [GET,POST] http://localhost:7071/api/RenewServiceConnectionAppCredentials

        ReportResourceGroupAssets: [GET] http://localhost:7071/api/ReportResourceGroupAssets

        VerifyResourceGroupAssets: [GET] http://localhost:7071/api/VerifyResourceGroupAssets

        NotifyWebhookExpiration: timerTrigger

For detailed output, run func with --verbose flag.

[2021-04-21T14:25:38.941Z] System Log: { Log-Level: Information; Log-Message: Azure Functions PowerShell Worker version [3.0.629.0]. }

[2021-04-21T14:25:40.279Z] System Log: { Log-Level: Information; Log-Message: PowerShell version: '7.0.3'. }

[2021-04-21T14:25:40.487Z] Retrying to start listener for function 'Functions.NotifyWebhookExpiration' (Attempt 1)

[2021-04-21T14:25:40.490Z] Unable to acquire Singleton lock (win8ffsquvonu-874168845/Host.Functions.NotifyWebhookExpiration.Listener).

[2021-04-21T14:25:40.492Z] Listener successfully started for function 'Functions.NotifyWebhookExpiration' after 1 retries.

[2021-04-21T14:25:40.667Z] System Log: { Log-Level: Information; Log-Message: The enforced concurrency level (pool size limit) is '10'. }

[2021-04-21T14:25:40.698Z] Established RPC channel. WorkerId: 4f20f126-b761-4ef7-8d68-878d04549d66

[2021-04-21T14:25:40.702Z] Worker Process started. Received StartStream message

[2021-04-21T14:25:40.760Z] System Log: { Log-Level: Trace; Log-Message: Custom pipe name specified. You can attach to the process by using vscode or by running `Enter-PSHostProcess -CustomPipeName AzureFunctionsPSWorker` }

[2021-04-21T14:25:40.804Z] Received WorkerInitResponse. Worker process initialized

[2021-04-21T14:25:40.822Z] Worker capabilities: { "RpcHttpBodyOnly": "true" }

[2021-04-21T14:25:40.825Z] Updating capabilities: { "RpcHttpBodyOnly": "true" }

[2021-04-21T14:25:40.831Z] Adding jobhost language worker channel for runtime: powershell. workerId:4f20f126-b761-4ef7-8d68-878d04549d66

[2021-04-21T14:25:40.843Z] Sending FunctionLoadRequest for function:Get-DuplicateSubnets with functionId:ed017d8b-6ac2-44c7-9ef4-f415d0fc9ac0

[2021-04-21T14:25:40.852Z] Adding dependency download request to powershell language worker

[2021-04-21T14:25:40.857Z] Sending FunctionLoadRequest for function:GetResourceGroupAuditHistory with functionId:2018c30b-d252-482b-9a4a-110799f6c8f7

[2021-04-21T14:25:40.859Z] Adding dependency download request to powershell language worker

[2021-04-21T14:25:40.877Z] Sending FunctionLoadRequest for function:NotifyWebhookExpiration with functionId:39568700-ae29-4429-b8f3-a3503da05add

[2021-04-21T14:25:40.892Z] Adding dependency download request to powershell language worker

[2021-04-21T14:25:40.894Z] Sending FunctionLoadRequest for function:RenewServiceConnectionAppCredentials with functionId:84e570d4-8e7c-464f-a047-e42483569d38

[2021-04-21T14:25:40.900Z] Adding dependency download request to powershell language worker

[2021-04-21T14:25:40.907Z] Sending FunctionLoadRequest for function:ReportResourceGroupAssets with functionId:d8e17872-48b4-4d8f-8805-d0092174e049

[2021-04-21T14:25:40.909Z] Adding dependency download request to powershell language worker

[2021-04-21T14:25:40.914Z] Sending FunctionLoadRequest for function:VerifyResourceGroupAssets with functionId:9764067f-8e8c-467d-9264-c9c0caf0cf37

[2021-04-21T14:25:40.922Z] Adding dependency download request to powershell language worker

[2021-04-21T14:25:40.924Z] Worker process started and initialized.

[2021-04-21T14:25:40.975Z] System Log: { Log-Level: Trace; Log-Message: Updating dependencies folder heartbeat for 'C:\Users\jt.pierce\AppData\Local\AzureFunctions\UtilityFunctions\ManagedDependencies\210409171842709.r''. }

[2021-04-21T14:25:41.013Z] System Log: { Log-Level: Trace; Log-Message: First function load request completed in 134 ms. }

[2021-04-21T14:25:41.022Z] System Log: { Log-Level: Trace; Log-Message: The function app has existing dependencies installed. Updating the dependencies to the latest versions will be performed in the background. New function app instances will pick up any new dependencies. }

[2021-04-21T14:25:41.143Z] Received FunctionLoadResponse for functionId:ed017d8b-6ac2-44c7-9ef4-f415d0fc9ac0

[2021-04-21T14:25:41.275Z] Received FunctionLoadResponse for functionId:2018c30b-d252-482b-9a4a-110799f6c8f7

[2021-04-21T14:25:41.283Z] Received FunctionLoadResponse for functionId:39568700-ae29-4429-b8f3-a3503da05add

[2021-04-21T14:25:41.372Z] Received FunctionLoadResponse for functionId:84e570d4-8e7c-464f-a047-e42483569d38

[2021-04-21T14:25:41.391Z] Received FunctionLoadResponse for functionId:d8e17872-48b4-4d8f-8805-d0092174e049

[2021-04-21T14:25:41.428Z] Received FunctionLoadResponse for functionId:9764067f-8e8c-467d-9264-c9c0caf0cf37

[2021-04-21T14:25:43.479Z] Host lock lease acquired by instance ID '00000000000000000000000074D3F107'.
SydneyhSmith commented 3 years ago

Thanks @avacoder could you please also provide what version of the PowerShell extension you are using?

ghost commented 3 years ago

This issue is being closed as inactive, if this issue is still occurring it will be re-opened