web
You’re offline. This is a read only version of the page.
close
Skip to main content
Community site session details

Community site session details

Session Id :
Microsoft Dynamics CRM (Archived)

Understanding CRM IIS Logs

(0) ShareShare
ReportReport
Posted on by

I am doing a performance analysis and found that CRM fires multiple SQL Queries for an OrganizationService operation. I also see some IIS Logs having logged with multiple CRM WebService operations example following:

+OnBeginRequest:07/07/2017-07:19:36.586+SdkB(Update:1):07:19:36.640+SdkB(RetrieveMultiple:150):07:19:39.194+SdkE:07:19:39.267+LogEntries:0+SDKSqlCalls:1+SDKSqlCallsMs:69+GC:40+SdkB(Retrieve:8):07:19:39.269+SdkE:07:19:39.760+LogEntries:0+SDKSqlCalls:1+SDKSqlCallsMs:490+GC:40+SdkB(Retrieve:10170):07:19:39.761+SdkE:07:19:39.812+LogEntries:0+SDKSqlCalls:1+SDKSqlCallsMs:49+GC:40+SdkB(RetrieveMultiple:10211):07:19:39.812+SdkE:07:19:39.833+LogEntries:0+SDKSqlCalls:1+SDKSqlCallsMs:19+GC:40+SdkB(RetrieveMultiple:10250):07:19:39.835+SdkE:07:19:39.841+LogEntries:0+SDKSqlCalls:1+SDKSqlCallsMs:4+GC:40+SdkB(Retrieve:10170):07:19:39.842+SdkE:07:19:39.857+LogEntries:0+SDKSqlCalls:1+SDKSqlCallsMs:13+GC:40+SdkB(RetrieveMultiple:10181):07:19:39.858+SdkE:07:19:40.482+LogEntries:0+SDKSqlCalls:1+SDKSqlCallsMs:621+GC:40+SdkB(RetrieveMultiple:1):07:19:40.483+SdkE:07:19:40.792+LogEntries:0+SDKSqlCalls:1+SDKSqlCallsMs:303+GC:40+SdkB(RetrieveMultiple:10250):07:19:40.794+SdkE:07:19:41.393+LogEntries:0+SDKSqlCalls:1+SDKSqlCallsMs:597+GC:40+SdkB(RetrieveMultiple:150):07:19:41.394+SdkE:07:19:41.435+LogEntries:0+SDKSqlCalls:1+SDKSqlCallsMs:37+GC:40+SdkB(Retrieve:8):07:19:41.437+SdkE:07:19:41.442+LogEntries:0+SDKSqlCalls:1+SDKSqlCallsMs:4+GC:40+SdkB(Retrieve:8):07:19:41.443+SdkE:07:19:41.449+LogEntries:0+SDKSqlCalls:1+SDKSqlCallsMs:6+GC:40+SdkB(RetrieveMultiple:10250):07:19:41.452+SdkE:07:19:41.623+LogEntries:0+SDKSqlCalls:1+SDKSqlCallsMs:169+GC:40+SdkB(RetrieveMultiple:10250):07:19:41.626+SdkE:07:19:41.651+LogEntries:0+SDKSqlCalls:1+SDKSqlCallsMs:23+GC:40+SdkB(Retrieve:10170):07:19:41.651+SdkE:07:19:41.660+LogEntries:0+SDKSqlCalls:1+SDKSqlCallsMs:7+GC:40+SdkE:07:19:43.921+LogEntries:0+SDKSqlCalls:15+SDKSqlCallsMs:2216+GC:40+LogEntries:0+SqlCalls:0+SqlCallsMs:0+GC:40+OnEndRequest:07:19:43.947


+OnBeginRequest:07/07/2017-07:20:09.428+SdkB(Update:10164):07:20:09.505+SdkE:07:20:18.005+LogEntries:0+SDKSqlCalls:25+SDKSqlCallsMs:7987+GC:40+LogEntries:0+SqlCalls:0+SqlCallsMs:0+GC:40+OnEndRequest:07:20:18.021

Please can somebody explain how CRM logs its operations? Ex: An Update is followed by RetrieveMultiple, I am assuming that this call was for Update of an entity.

Also, I see various fields like SdkB and SdkE, SDKSqlCalls:1+SDKSqlCallsMs:17. Please can somebody explain how this can be understood.  I am taking DB Traces and trying to understand the associated queries (based on Time Frame).

Is there any way to map SQL Queries with the Operations ex: some kind of RequestId? Thanks in advance for help.

*This post is locked for comments

I have the same question (0)

Under review

Thank you for your reply! To ensure a great experience for everyone, your content is awaiting approval by our Community Managers. Please check back later.

Helpful resources

Quick Links

Responsible AI policies

As AI tools become more common, we’re introducing a Responsible AI Use…

Abhilash Warrier – Community Spotlight

We are honored to recognize Abhilash Warrier as our Community Spotlight honoree for…

Leaderboard > 🔒一 Microsoft Dynamics CRM (Archived)

#1
HR-09070029-0 Profile Picture

HR-09070029-0 2

#1
UllrSki Profile Picture

UllrSki 2

#3
ED-30091530-0 Profile Picture

ED-30091530-0 1

Last 30 days Overall leaderboard

Featured topics

Product updates

Dynamics 365 release plans