Some Eclipse Foundation services are deprecated, or will be soon. Please ensure you've read this important communication.

Bug 332815

Summary: Missing Bundle Classpath entries
Product: z_Archived Reporter: Lionel PJ <pjlionel>
Component: BIRTAssignee: Birt-ReportViewer <Birt-ReportViewer-inbox>
Status: RESOLVED WONTFIX QA Contact: Hao Zhou <hao.zhou>
Severity: critical    
Priority: P3 CC: bluesoldier, jouyang, pjlionel
Version: 2.6.1   
Target Milestone: ---   
Hardware: PC   
OS: Windows XP   
Whiteboard:
Attachments:
Description Flags
additional trace files - 1
none
additional trace files - 2
none
additional trace files - 3
none
additional trace files - 4 none

Description Lionel PJ CLA 2010-12-16 23:42:08 EST 
Build Identifier: Birt 2.6.1 Runtime for JBoss

I had downloaded Birt 2.6.0 and 2.6.1 and deployed them in JBoss. On the initial load of an excel report, it always take more than 25 seconds. Upon using dynaTrace we found that tests.jar and modelFragment_internal.jar are missing within the downloaded BIRT runtime (org.eclipse.birt.report.model_2.6.0.v20100603.jar\META-INF\MANIFEST.MF - Bundle-ClassPath: tests.jar,modelFragment_internal.jar,.)
Similarly, library.jar is missing and has a reference in org.eclipse.birt.report.data.adapter_2.6.0.v20100525.jar with its bundle classpath as Bundle-ClassPath: library.jar,.

Complete trace of all errors on initial load is as given below (xml format)

<?xml version="1.0" encoding="utf-8"?><purepath_execution_tree><nodeinfo level="2" method="service(HttpServletRequest req, HttpServletResponse resp)" class="AxisServletBase" argument="" return="" agent="QAPartitionWeb4@execomjbqa4:1840" api="Servlet, Java Web Services" total_ms="18797.963292952627" exec_ms="16607.11396037822" exec="88.33192" cpu_total_ms="12625.0" cpu_exec_ms="12132.965805766871" cpu="96.1027" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;66884950&gt;" elapsed_time_ms="2.789634306449443"/><nodeinfo level="3" method="exception" class="BundleException" argument="The bundle class path entry &quot;tests.jar&quot; could not be found for the bundle &quot;update@plugins/org.eclipse.birt.report.model_2.6.0.v20100603.jar&quot;" return="" agent="QAPartitionWeb4@execomjbqa4:1840" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;66884950&gt;" elapsed_time_ms="199.08867840724997"/><nodeinfo level="3" method="exception" class="BundleException" argument="The bundle class path entry &quot;modelFragment_internal.jar&quot; could not be found for the bundle &quot;update@plugins/org.eclipse.birt.report.model_2.6.0.v20100603.jar&quot;" return="" agent="QAPartitionWeb4@execomjbqa4:1840" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;66884950&gt;" elapsed_time_ms="199.29649988014717"/><nodeinfo level="3" method="exception" class="IllegalIcuArgumentException" argument="Invalid name: ID_Start" return="" agent="QAPartitionWeb4@execomjbqa4:1840" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;66884950&gt;" elapsed_time_ms="8108.474875099142"/><nodeinfo level="3" method="exception" class="IllegalIcuArgumentException" argument="Invalid name: ID_Start" return="" agent="QAPartitionWeb4@execomjbqa4:1840" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;66884950&gt;" elapsed_time_ms="8108.609812069684"/><nodeinfo level="3" method="exception" class="IllegalIcuArgumentException" argument="Invalid name: ID_Continue" return="" agent="QAPartitionWeb4@execomjbqa4:1840" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;66884950&gt;" elapsed_time_ms="8288.546781835961"/><nodeinfo level="3" method="exception" class="IllegalIcuArgumentException" argument="Invalid name: ID_Continue" return="" agent="QAPartitionWeb4@execomjbqa4:1840" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;66884950&gt;" elapsed_time_ms="8288.670247829054"/><nodeinfo level="3" method="exception" class="BundleException" argument="The bundle class path entry &quot;library.jar&quot; could not be found for the bundle &quot;update@plugins/org.eclipse.birt.report.data.adapter_2.6.0.v20100525.jar&quot;" return="" agent="QAPartitionWeb4@execomjbqa4:1840" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;66884950&gt;" elapsed_time_ms="11184.395784266759"/><nodeinfo level="3" method="exception" class="BundleException" argument="The bundle class path entry &quot;library.jar&quot; could not be found for the bundle &quot;update@plugins/org.eclipse.birt.data_2.6.0.v20100524.jar&quot;" return="" agent="QAPartitionWeb4@execomjbqa4:1840" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;66884950&gt;" elapsed_time_ms="11210.631280393689"/><nodeinfo level="3" method="log4j [ERROR]" class="STDERR" argument="Dec 16, 2010 4:44:11 PM java.util.logging.LogManager$RootLogger log
INFO: **INFO: webExpId = 860604100, plant = 10, eAdmin = T, soldToNumber = null" return="" agent="QAPartitionWeb4@execomjbqa4:1840" api="Log4J" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;66884950&gt;" elapsed_time_ms="13140.489285131567"/><nodeinfo level="3" method="executeQuery(String sql)" class="JtdsStatement" argument="SELECT @@MAX_PRECISION
SET TRANSACTION ISOLATION LEVEL READ COMMITTED
SET IMPLICIT_TRANSACTIONS OFF
SET QUOTED_IDENTIFIER ON
SET TEXTSIZE 2147483647" return="net.sourceforge.jtds.jdbc.JtdsResultSet@371eb70" agent="QAPartitionWeb4@execomjbqa4:1840" api="JDBC" total_ms="88.8146152497502" exec_ms="88.8146152497502" exec="0.47239783" cpu_total_ms="62.5" cpu_exec_ms="62.5" cpu="0.4950495" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;66884950&gt;" elapsed_time_ms="13655.409178444417"/></purepath_execution_tree>

This needs immediate attention as my project is udnergoing performance tests and this is one of the critical issues that is stopping from us moving it to production. At times we get OutOfMemoryErrors.

Reproducible: Always

Steps to Reproduce:
1. Rename WebViewerExample and deploy as birt.war under jboss deploy folder. 
2. Create a rptdesign file and copy it to the reports folder
3. Modify the report location to the report folder
4. View the report in excel format after a fresh start of jboss or a restart.
5. Look at the trace using any tool similar to dynaTrace/
Comment 1 Lionel PJ CLA 2010-12-16 23:57:14 EST 
Created attachment 185394 [details]
additional trace files - 1

additional trace files - 1
Comment 2 Lionel PJ CLA 2010-12-16 23:58:02 EST 
Created attachment 185395 [details]
additional trace files - 2
Comment 3 Lionel PJ CLA 2010-12-16 23:58:42 EST 
Created attachment 185396 [details]
additional trace files - 3
Comment 4 Lionel PJ CLA 2010-12-16 23:59:11 EST 
Created attachment 185397 [details]
additional trace files - 4
Comment 5 Jun Ouyang CLA 2010-12-17 02:36:34 EST 
Does this problem only exist when preview as excel?
Comment 6 Xiaoying Gu CLA 2010-12-17 02:38:14 EST 
The missed lib are required for junit test, not birt web viewer.
It should not related to the first load performance issue.


Do you have this issue if run the report as other format, such as pdf, html?
Comment 7 Lionel PJ CLA 2010-12-17 08:19:13 EST 
(In reply to comment #5)
> Does this problem only exist when preview as excel?

Yes.

I have been trying to make my app work with /run in the qa and other environments but unfortunately something is conflicting and I have not found enough time to exclude the issue. Luckily it works with /preview. So I am using it as a standby solution till I figure it out.
Comment 8 Lionel PJ CLA 2010-12-17 08:25:46 EST 
(In reply to comment #6)
> The missed lib are required for junit test, not birt web viewer.
> It should not related to the first load performance issue.
> 
> 
> Do you have this issue if run the report as other format, such as pdf, html?

I will try it out today when I make a build to the qa environment and let you know.

I started this investigation yesterday because I saw two distinct issues only with birt reports on two different qa boxes.

The messages were -

qa3 -
java.lang.NoClassDefFoundError: org/eclipse/birt/report/model/api/util/StringUtil
org.eclipse.birt.report.model.metadata.MetaDataHandlerImpl$ChoiceTypeState.parseAttrs(MetaDataHandlerImpl.java:232)
org.eclipse.birt.report.model.util.XMLParserHandler.startElement(XMLParserHandler.java:162)
org.apache.xerces.parsers.AbstractSAXParser.startElement(Lorg.apache.xerces.xni.QName;Lorg.apache.xerces.xni.XMLAttributes;Lorg.apache.xerces.xni.Augmentations;)V(Unknown Source)

resolved it by a restart of that box

qa4 -
java.lang.OutOfMemoryError: class allocation JVM@check_alloc (../../../../src/jvm/model/reflect/class.c:732). 1440 bytes requested. Java heapsize=157286400

Dont know why this occurred when I tried to get the excel download. So, I just wanted to make sure that by the policy of exclusion that each component in my app is not the bad performer. Though, I have sent you this message, please understand that I am also trying to identify the culprit if its within my app too.

Otherwise, I am not sure why the first time should take a variable amount of time from 15 to 38 seconds. Dont know why, but it does.
Comment 9 Lionel PJ CLA 2010-12-17 11:02:18 EST 
(In reply to comment #8)
> (In reply to comment #6)
> > The missed lib are required for junit test, not birt web viewer.
> > It should not related to the first load performance issue.

So today I tried to use it with /run on my qa4 box and I got the same out of memory error

https://[concealed]/birt/run?__report=CompanyStandards/eAdminExportList.rptdesign&webExpId=860604100&__format=xls&eAdmin=T

and dynaTrace had just 4 lines 

<?xml version="1.0" encoding="utf-8"?><purepath_execution_tree><nodeinfo level="0" method="doFilter(ServletRequest request, ServletResponse response, FilterChain chain)" class="ReplyHeaderFilter" argument="" return="" agent="QAPartitionWeb4@execomjbqa4:7312" api="Servlet" total_ms="10511.640595026314" exec_ms="1.7622696198523045" exec="0.016764933" cpu_total_ms="8953.125" cpu_exec_ms="0.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor1 &lt;71333877&gt;" elapsed_time_ms="0.0"/><nodeinfo level="1" method="doFilter(ServletRequest request, ServletResponse response, FilterChain chain)" class="ViewerFilter" argument="" return="" agent="QAPartitionWeb4@execomjbqa4:7312" api="Servlet" total_ms="10509.878325406462" exec_ms="2.4085716418921947" exec="0.022913374" cpu_total_ms="8953.125" cpu_exec_ms="0.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor1 &lt;71333877&gt;" elapsed_time_ms="1.742969322949648"/><nodeinfo level="2" method="service(HttpServletRequest req, HttpServletResponse resp)" class="AxisServletBase" argument="" return="" agent="QAPartitionWeb4@execomjbqa4:7312" api="Servlet" total_ms="10507.46975376457" exec_ms="10507.46975376457" exec="99.96032" cpu_total_ms="8953.125" cpu_exec_ms="8953.125" cpu="100.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor1 &lt;71333877&gt;" elapsed_time_ms="3.67828468978405"/><nodeinfo level="3" method="exception" class="BundleException" argument="The bundle class path entry &quot;tests.jar&quot; could not be found for the bundle &quot;update@plugins/org.eclipse.birt.report.model_2.6.0.v20100603.jar&quot;" return="" agent="QAPartitionWeb4@execomjbqa4:7312" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor1 &lt;71333877&gt;" elapsed_time_ms="257.3999493718147"/><nodeinfo level="3" method="exception" class="BundleException" argument="The bundle class path entry &quot;modelFragment_internal.jar&quot; could not be found for the bundle &quot;update@plugins/org.eclipse.birt.report.model_2.6.0.v20100603.jar&quot;" return="" agent="QAPartitionWeb4@execomjbqa4:7312" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor1 &lt;71333877&gt;" elapsed_time_ms="257.63657151162624"/></purepath_execution_tree></xml>
Comment 10 Lionel PJ CLA 2010-12-17 11:15:56 EST 
(In reply to comment #6)
> The missed lib are required for junit test, not birt web viewer.
> It should not related to the first load performance issue.
> 
> 
> Do you have this issue if run the report as other format, such as pdf, html?

I tried to execute the same report with pdf format and I get a slow response (almost 30 secs)

Trace as given below - 
<?xml version="1.0" encoding="utf-8"?><purepath_execution_tree><nodeinfo level="0" method="doFilter(ServletRequest request, ServletResponse response, FilterChain chain)" class="ReplyHeaderFilter" argument="" return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Servlet" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="0.0"/><nodeinfo level="1" method="doFilter(ServletRequest request, ServletResponse response, FilterChain chain)" class="ViewerFilter" argument="" return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Servlet" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="0.9300064668059349"/><nodeinfo level="2" method="service(HttpServletRequest req, HttpServletResponse resp)" class="AxisServletBase" argument="" return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Servlet" total_ms="-1.0" exec_ms="0.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="0.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="1.9510859623551369"/><nodeinfo level="3" method="exception" class="BundleException" argument="The bundle class path entry &quot;tests.jar&quot; could not be found for the bundle &quot;update@plugins/org.eclipse.birt.report.model_2.6.0.v20100603.jar&quot;" return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="642.3866720497608"/><nodeinfo level="3" method="exception" class="BundleException" argument="The bundle class path entry &quot;modelFragment_internal.jar&quot; could not be found for the bundle &quot;update@plugins/org.eclipse.birt.report.model_2.6.0.v20100603.jar&quot;" return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="642.569656200707"/><nodeinfo level="3" method="exception" class="IllegalIcuArgumentException" argument="Invalid name: ID_Start" return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="8875.769965179265"/><nodeinfo level="3" method="exception" class="IllegalIcuArgumentException" argument="Invalid name: ID_Start" return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="8875.881431855261"/><nodeinfo level="3" method="exception" class="IllegalIcuArgumentException" argument="Invalid name: ID_Continue" return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="9054.630965664983"/><nodeinfo level="3" method="exception" class="IllegalIcuArgumentException" argument="Invalid name: ID_Continue" return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="9054.739079967141"/><nodeinfo level="3" method="exception" class="BundleException" argument="The bundle class path entry &quot;library.jar&quot; could not be found for the bundle &quot;update@plugins/org.eclipse.birt.report.engine.emitter.pdf_2.6.0.v20100513.jar&quot;" return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="11799.672304719687"/><nodeinfo level="3" method="exception" class="BundleException" argument="The bundle class path entry &quot;library.jar&quot; could not be found for the bundle &quot;update@plugins/org.eclipse.birt.report.data.adapter_2.6.0.v20100525.jar&quot;" return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="13318.154402308166"/><nodeinfo level="3" method="exception" class="BundleException" argument="The bundle class path entry &quot;library.jar&quot; could not be found for the bundle &quot;update@plugins/org.eclipse.birt.data_2.6.0.v20100524.jar&quot;" return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="13360.19102986902"/><nodeinfo level="3" method="log4j [ERROR]" class="STDERR" argument="Dec 17, 2010 9:09:21 AM java.util.logging.LogManager$RootLogger log
INFO: **INFO: webExpId = 860604100, plant = 10, eAdmin = T, soldToNumber = null" return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Log4J" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="16401.606349408627"/><nodeinfo level="3" method="executeQuery(String sql)" class="JtdsStatement" argument="SELECT @@MAX_PRECISION
SET TRANSACTION ISOLATION LEVEL READ COMMITTED
SET IMPLICIT_TRANSACTIONS OFF
SET QUOTED_IDENTIFIER ON
SET TEXTSIZE 2147483647" return="net.sourceforge.jtds.jdbc.JtdsResultSet@28ab480" agent="QAPartitionWeb1@execomjbqa1:7076" api="JDBC" total_ms="74.63043487071991" exec_ms="74.63043487071991" exec="0.33086628" cpu_total_ms="62.5" cpu_exec_ms="62.5" cpu="14.181839" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="16857.0527818501"/><nodeinfo level="3" method="prepareCall(String sql)" class="ConnectionJDBC2" argument="{call cs_export_list_for_web_grp(?)}" return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="JDBC" total_ms="11.117055378854275" exec_ms="11.117055378854275" exec="0.049286313" cpu_total_ms="11.117055378854275" cpu_exec_ms="11.117055378854275" cpu="2.5225646" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="16982.6176231876"/><nodeinfo level="3" method="execute()" class="JtdsPreparedStatement" argument="{call cs_export_list_for_web_grp(?)}" return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="JDBC" total_ms="16.155125856399536" exec_ms="16.155125856399536" exec="0.07162207" cpu_total_ms="15.625" cpu_exec_ms="15.625" cpu="3.5454597" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="16997.131758369505"/><nodeinfo level="3" method="getProducts(ProductsRequest)" class="$Proxy165" argument="" return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Search" total_ms="2129.753921240568" exec_ms="7.632813669741154" exec="0.033839285" cpu_total_ms="288.88281366974115" cpu_exec_ms="7.632813669741154" cpu="1.7319573" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="17310.66965214908"/><nodeinfo level="4" method="invoke(Object proxy, Method method, Object[] args)" class="HessianProxy" argument="" return="com.insight.search.dto.response.ProductsResponse@30c616d[
  products=[com.insight.search.dto.model.Product@31000c5[
  id=1033698
  materialId=40Y7659
  parentCategoryId=&lt;null&gt;
  parentCategoryLabel=&lt;null&gt;
  categoryId=C-ZA
  categoryLabel=&lt;nul" agent="QAPartitionWeb1@execomjbqa1:7076" api="Java Web Services" total_ms="2122.121107570827" exec_ms="2122.121107570827" exec="9.408203" cpu_total_ms="281.25" cpu_exec_ms="281.25" cpu="63.818275" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="17318.27089755982"/><nodeinfo level="3" method="getProducts(ProductsRequest)" class="$Proxy165" argument="" return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Search" total_ms="1541.3034338206053" exec_ms="0.0843682661652565" exec="3.7403795E-4" cpu_total_ms="31.25" cpu_exec_ms="0.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="19467.360516488552"/><nodeinfo level="4" method="invoke(Object proxy, Method method, Object[] args)" class="HessianProxy" argument="" return="com.insight.search.dto.response.ProductsResponse@2fc16f9[
  products=[com.insight.search.dto.model.Product@2fc185e[
  id=959795
  materialId=B2M-00012
  parentCategoryId=&lt;null&gt;
  parentCategoryLabel=&lt;null&gt;
  categoryId=C-DA
  categoryLabel=&lt;nu" agent="QAPartitionWeb1@execomjbqa1:7076" api="Java Web Services" total_ms="1541.21906555444" exec_ms="1541.21906555444" exec="6.8328342" cpu_total_ms="31.25" cpu_exec_ms="31.25" cpu="7.0909195" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="19467.414154589176"/><nodeinfo level="3" method="getProducts(ProductsRequest)" class="$Proxy165" argument="" return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Search" total_ms="927.0275970846415" exec_ms="0.07961905747652054" exec="3.5298284E-4" cpu_total_ms="15.70461905747652" cpu_exec_ms="0.07961905747652054" cpu="0.018066315" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="21015.761500410736"/><nodeinfo level="4" method="invoke(Object proxy, Method method, Object[] args)" class="HessianProxy" argument="" return="com.insight.search.dto.response.ProductsResponse@2e82c13[
  products=[com.insight.search.dto.model.Product@2e82d79[
  id=969098
  materialId=99-PACK2
  parentCategoryId=&lt;null&gt;
  parentCategoryLabel=&lt;null&gt;
  categoryId=C-ZM
  categoryLabel=&lt;nul" agent="QAPartitionWeb1@execomjbqa1:7076" api="Java Web Services" total_ms="926.9479780271649" exec_ms="926.9479780271649" exec="4.1095276" cpu_total_ms="15.625" cpu_exec_ms="15.625" cpu="3.5454597" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="21015.80983057618"/><nodeinfo level="3" method="getProducts(ProductsRequest)" class="$Proxy165" argument="" return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Search" total_ms="605.7683308944106" exec_ms="0.07766350358724594" exec="3.4431307E-4" cpu_total_ms="15.625" cpu_exec_ms="0.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="21950.302901625633"/><nodeinfo level="4" method="invoke(Object proxy, Method method, Object[] args)" class="HessianProxy" argument="" return="com.insight.search.dto.response.ProductsResponse@2d40b36[
  products=[com.insight.search.dto.model.Product@2d40c9b[
  id=1001796
  materialId=99-ASSET
  parentCategoryId=&lt;null&gt;
  parentCategoryLabel=&lt;null&gt;
  categoryId=C-ZM
  categoryLabel=&lt;nu" agent="QAPartitionWeb1@execomjbqa1:7076" api="Java Web Services" total_ms="605.6906673908234" exec_ms="605.6906673908234" exec="2.6852667" cpu_total_ms="15.625" cpu_exec_ms="15.625" cpu="3.5454597" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="21950.34927623719"/><nodeinfo level="3" method="log4j [ERROR]" class="STDERR" argument="Dec 17, 2010 9:09:27 AM java.util.logging.LogManager$RootLogger log
INFO: **INFO: exportedProducts size = 335" return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Log4J" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="22588.478703297675"/><nodeinfo level="3" method="exception" class="XMLEntityScanner$1" argument="" return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="25660.247880667448"/><nodeinfo level="3" method="exception" class="DocumentException" argument="Font 'Times New Roman' with 'Identity-H' is not recognized." return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="27087.15074122697"/><nodeinfo level="3" method="exception" class="DocumentException" argument="Font 'SimSun' with 'Identity-H' is not recognized." return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="27102.957219421864"/><nodeinfo level="3" method="exception" class="DocumentException" argument="Font 'Batang' with 'Identity-H' is not recognized." return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="27103.218705169857"/><nodeinfo level="3" method="exception" class="DocumentException" argument="Font 'MS PMincho' with 'Identity-H' is not recognized." return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="27103.34190518409"/><nodeinfo level="3" method="exception" class="DocumentException" argument="Font 'Tahoma' with 'Identity-H' is not recognized." return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="27103.462311550975"/><nodeinfo level="3" method="exception" class="DocumentException" argument="Font 'Times New Roman Bold' with 'Identity-H' is not recognized." return="" agent="QAPartitionWeb1@execomjbqa1:7076" api="Exception" total_ms="-1.0" exec_ms="-1.0" exec="-1.0" cpu_total_ms="-1.0" cpu_exec_ms="-1.0" cpu="-1.0" sync_total_ms="-1.0" sync_ms="-1.0" sync="-1.0" wait_total_ms="-1.0" wait_ms="-1.0" wait="-1.0" suspension_total_ms="-1.0" suspension_ms="-1.0" suspension="-1.0" thread_name="TP-Processor2 &lt;11539293&gt;" elapsed_time_ms="28135.04034730792"/></purepath_execution_tree></xml>
Comment 11 Lionel PJ CLA 2010-12-20 15:10:59 EST 
The has been a consistent memory leak after the first time load and we feel that it may be related to those exceptions that occur (as provided earlier). The JVM Memory usage hits a huge spike does not release back to normalcy after a period of time. 

Any help from your end will be of great help. At this moment, we are moving Birt into a different machine to isolate the problem. 

Thanks.
Comment 12 Lionel PJ CLA 2011-01-05 17:13:50 EST 
any updates?
Comment 13 Xiaoying Gu CLA 2011-10-31 03:55:04 EDT 
The first time is always slow as we need initialize/start the OSGi framework.

Suggest to set it to won't fixed.