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