Analyzing long running AL methods telemetry

> APPLIES TO: Business Central 2020 release wave 2 and later, version 17.1. Available in extension telemetry starting with version 18.1.

Note

Azure Active Directory is now Microsoft Entra ID. Learn more

The Business Central Server server will emit telemetry about the execution time of long running AL methods, including the time spent in the database. The signal also includes a breakdown of how much time each event subscriber added to the total time. As a partner, this data gives you insight into bad performing code and enables you to troubleshoot performance issues caused by extensions.

Note

To collect this telemetry for Business Central on-premises, the AL Function Timing and AL Function Logging Threshold - Application Insights settings must be configured on the Business Central Server instance. For more information, see Configuring Business Central Server. With Business Central online, this telemetry is enabled with a specific threshold on a case-by-case basis by the service.

General dimensions

The following table explains the general dimensions included in the trace. The table lists the dimensions that are specific to Business Central.

Dimension Description or value
message Operation exceeded time threshold (AL method)
severityLevel 2
user_Id The user telemetry ID for the user. From the user card, you can use user_Id to identify the user who triggered this telemetry event. For more information, see Assign a telemetry ID to users.

CustomDimensions

This table describes the different dimensions of a Operation exceeded time threshold (AL method) operation.

Column (key) Description or value
aadTenantId Specifies the Microsoft Entra tenant ID when using Microsoft Entra authentication. For on-premises, if you aren't using Microsoft Entra authentication, this value is common.
alMethod The name of the long running AL method.
alObjectId The type of the AL object that executed the AL method.
alObjectName The name of the AL object that executed the AL method.
alObjectType The type of the AL object that executed the AL method.
alStackTrace The stack trace in AL.
clientType Specifies the type of client that opened the session, such as Background or Web. For a list of the client types, see ClientType Option Type.
companyName The current company name.
component Dynamics 365 Business Central Server
componentVersion Specifies the version number of the component that emits telemetry (see the component dimension.)
deprecatedKeys A comma-separated list of all the keys that have been deprecated. The keys in this list are still supported but will eventually be removed in the next major release. We recommend that update any queries that use these keys to use the new key name.
environmentType Specifies the environment type of the Business Central solution, such as Production or Sandbox.
environmentName Specifies the environment name of the Business Central solution, such as Production or Sandbox.
eventId RT0018
exclusiveTime Specifies the execution time of the operation without the time where AL execution was suspended due to client callback (such as when waiting for user input.) The value has the format hh:mm:ss.sssssss.

Introduced in versions 21.6 and 22.1.
executionTime Specifies the total time that it took to execute the AL method. The value has the format hh:mm:ss.sssssss.
extensionInfo Specifies information about individual extensions that contributed to the execution time spent in the call stack up until the point at which the long-running threshold was exceeded and the trace was emitted. The following information is included for each extension:
  • id - the ID of the extension.
  • extensionName - the name of the extension.
  • extensionVersion - the version of the extension.
  • extensionPublisher - the publisher of the extension.
  • subscriberExecutionCount - the number of event subscribers executed in this extension.
  • executionTime - the total execution time for this extension in the call stack. The value has the format hh:mm:ss.sssssss.
extensionName Specifies the name of the extension that was currently executing when the long-running threshold was exceeded and the trace was emitted.
extensionPublisher Specifies the publisher of the extension that was currently executing when the long-running threshold was exceeded and the trace was emitted.
extensionVersion Specifies the version of the extension that was currently executing when the long-running threshold was exceeded and the trace was emitted.
extensionId Specifies the ID of the extension that was currently executing when the long-running threshold was exceeded and the trace was emitted.
longRunningThreshold Specifies the time that defines a long-running AL method, after which the trace is emitted. The value has the format hh:mm:ss.sssssss.
sqlExecutes Specifies the number of SQL statements that was executed in the method.

This dimension was introduced in Business Central 2023 release wave 1, version 22.0.
sqlRowsRead Specifies the number of table rows that were read by the SQL statements.

This dimension was introduced in Business Central 2023 release wave 1, version 22.0.
telemetrySchemaVersion Specifies the version of the Business Central telemetry schema.

Sample KQL code for analyzing AL method performance

Use this KQL code if you want to analyze AL method performance.

traces
| where timestamp > ago(60d) // adjust as needed
| where customDimensions.eventId == 'RT0018' 
| where customDimensions.alObjectId > 0 // filter out internal server calls
| project aadTenantId = customDimensions.aadTenantId
, environmentName = customDimensions.environmentName
, environmentType = customDimensions.environmentType
, companyName = customDimensions.companyName
, alMethod = customDimensions.alMethod
, alObjectId = customDimensions.alObjectId
, alObjectName = customDimensions.alObjectName
, alObjectType = customDimensions.alObjectType
, alStackTrace = customDimensions.alStackTrace // use the KQL snippet parseStackTrace to get top/bottom of the stack trace details
, clientType = customDimensions.clientType
// exclusiveTime is the execution time of the operation, without the time where AL execution was suspended due to client callback
, exclusiveTime = customDimensions.exclusiveTime // This dimension was introduced in Business Central 2023 release wave 1, version 22.1. Backported to version 21.6
, exclusiveTimeInMS = toreal(totimespan(customDimensions.exclusiveTime))/10000 //the datatype for exclusiveTime is timespan 
// exclusiveTime is the total time of the operation, including wait time due to client callback
, totalTime = customDimensions.executionTime
, totalTimeInMS = toreal(totimespan(customDimensions.executionTime))/10000 //the datatype for executionTime is timespan 
, extensionId = customDimensions.extensionId
, extensionInfo = customDimensions.extensionInfo // parse this json structure to find out if other extensions is involved
, extensionName = customDimensions.extensionName
, extensionPublisher = customDimensions.extensionPublisher
, extensionVersion = customDimensions.extensionVersion
, longRunningThreshold = customDimensions.longRunningThreshold
, longRunningThresholdInMS = toreal(totimespan(customDimensions.longRunningThreshold))/10000 //the datatype for executionTime is timespan 
, sqlExecutes = toint(customDimensions.sqlExecutes) // This dimension was introduced in Business Central 2023 release wave 1, version 22.0
, sqlRowsRead = toint(customDimensions.sqlRowsRead) // This dimension was introduced in Business Central 2023 release wave 1, version 22.0
, usertelemetryId = case(
  // user telemetry id was introduced in the platform in version 20.0
  toint( substring(customDimensions.componentVersion,0,2)) >= 20, user_Id
, 'N/A'
)

See also

Monitoring and Analyzing Telemetry
Enable Sending Telemetry to Application Insights
Monitoring and Analyzing Long Running SQL Queries On-Premises
The Business Central Administration Center