Power Tools: Optimizing AutoConfig Performance Through Profiling

[Editor's Note:  This is the first of a series of four articles on new AutoConfig features.  These articles are written by members of our AutoConfig Development team.  This is your chance to get the inside track on these advanced features and provide your feedback directly to our developers.]

Ever wonder what's taking up the time during a given AutoConfig run in your E-Business Suite environment?  Want to optimize the performance of your techstack configuration customizations?  The AutoConfig Performance Profiler gathers data about an AutoConfig run and generates a consolidated AutoConfig profile report in HTML format. The report lists all product tops processed by AutoConfig along with the total instantiation and execution time of the templates within them. A beta customer of this feature helped us fix an indexing issue to allow AutoConfig to run in one third of the time.

The generated performance report allows you to drill down on each product top and view the following:

  • Source and target location of individual templates
  • Time consumed to instantiate or execute each template
  • Execution report for each template

Here's a screenshot of the first few lines of the report:

Mini screenshot of AutoConfig Performane Profiler report

A complete sample performance profiler report can be found here.

Identitying AutoConfig Performance Bottlenecks

This report is useful in analyzing the source of AutoConfig performance bottlenecks. It also helps administrators optimize template customizations (if any). For example, if you have performed customizations to the context variable values or to any of the product templates and you find that AutoConfig is taking more time after the customization, you can use this feature and generate the profiler report to see where exactly the delay is occurring. The profiler report allows you to determine which phase or product took more time to execute.

Then by further clicking on the link for that phase or product, you can see more details at the template level. Going through these details, you can determine which templates are taking an unreasonable amount of time for instantiation or execution. You can use this to verify and optimize your customizations to the templates.

Generating AutoConfig Performance Profiler Reports

To generate the AutoConfig Performance Profiler report, you can run AutoConfig in 'profile mode' by issuing the following command:

Application Tier
 
perl $AD_TOP/bin/adconfig.pl contextfile=<CtxFile> [product=<product_top>] –profile

Database Tier
 
perl $ORACLE_HOME/appsutil/bin/adconfig.pl contextfile=<CtxFile> –profile

where

<CtxFile> is the absolute path to the context file
<product_top> is the Product short name

Note that the -profile option can be used alongside other AutoConfig command line parameters.

Downloading the Latest AutoConfig Engine

Customers on Oracle E-Business Suite Release 12 can obtain this new feature by installing:

Customers on 11i can get this new AutoConfig feature by installing:

Your Thoughts?

We would appreciate if you could share with us your experience on using this new feature. Please post your comments here or email your profiling results to Ivo Dumovic at:

ivo-dujmovic-email.png

We're eager to hear about your thoughts about how we can improve this feature.

References

Related Articles

Comments:

Hello Srinivas,

First of all let me say thank you for all you are doing to improve ATG stack and simplify Apps DBA live.
The autoconfig profiling looks absolutely great and necessary!
Just wonder how close the example you provided to the production version?
====================================================================
From the first look I found that this particular report missing ~25% of total execution time:
Autoconfig Summary Total Time (sec) 213
Context Value Management Total Time (sec) 19.91
Product Summary Report Total Time (sec) 135.81
19.91 + 135.81 = 155.72 &lt;> 213 sec (57.28 sec are missing).
A. What does that mean? Where 57 secs from 213 reported have been spent?
====================================================================
It a bit of fun looking on
Product Summary Report => Total => Total Time (sec) => 135.81
If you would sum that column numbers for all rows you will get => 115.9
B. Is there mistake in my calculations? Is it expected behaviour or something else?
====================================================================
C. Can I propose an enhancement to sort records in report tables by "Total Time (sec)". As from my point of view 95% people will use that report to find longest executed steps. I had to copy/paste the report figures to get the answer to my question.

Once again! Thank you very much for making Apps DBA easer and improving ATG functionality.

With all my respect,
Yury

Posted by Jurijs Velikanovs on April 03, 2009 at 04:32 PM PDT #

Hi Jurijs,

First of all thanks for your feedback. Here are the answers to the above questions.

B. Is there mistake in my calculations? Is it expected behavior or something else?

Ans:
The total time (135.81) in the report includes the time taken by the CVM phase (19.91 + 115.9 = 135.81)

A. What does that mean? Where 57 secs from 213 reported have been spent?

Ans:
"Autoconfig Summary Total Time (sec) 213" reports the total time taken by AutoConfig to complete from start to finish.

The product wise drill down report only shows the time taken by AutoConfig to instantiate/execute product templates, it does not factor in the time taken by AutoConfig for its own internal working.

C. Proposal to sort records in report tables by "total time".

Ans:
This is a good enhancement to have. We'll evaluate the proposal internally.

Thanks
Srinivas

Posted by Srinivas Chaganti on April 05, 2009 at 04:12 PM PDT #

Srinivas,

If the 135.81 figures includes CVM phase then we are missing even more time:
213 sec - 135 sec = 78 sec => 37% of total execution time is not reflected in the report.

Looking on the report title "Autoconfig Performance Profile Report" someone can get a wrong impression that we are talking about full time spent to execute atutoconfig.
At least I have got that impression.

--------------
>> The product wise drill down report only shows the time taken by AutoConfig to instantiate/execute product templates, it does >> not factor in the time taken by AutoConfig for its own internal working.
--------------
Your comment clarifies a lot to me.
Just wonder if you documented this somewhere to avoid misunderstanding on Apps DBA side?

Thanks for talking with us :)
Yury

Posted by Jurijs Velikanovs on April 05, 2009 at 09:39 PM PDT #

Post a Comment:
  • HTML Syntax: NOT allowed
About

Search

Categories
Archives
« April 2014
SunMonTueWedThuFriSat
  
1
4
5
6
7
8
9
10
11
12
13
14
17
18
19
20
21
22
23
24
25
26
27
28
29
30
   
       
Today