[OGNL-247] Major performance Bootle neck under heavy load

• Type: Bug
• Status: Closed
• Priority: Major
• Resolution: Duplicate
• Affects Version/s: 3.0
• Fix Version/s: 3.0
• Component/s: PropertyAccessors
• Labels: performance
• Environment: Struts2, Jettym, Windows
• Assignee: Unassigned
• Reporter: Lokesha
• Created: 05/Jun/14 10:14
• Updated: 05/Jun/14 19:29
• Resolved: 05/Jun/14 19:29

Description

was testing one of my high profile application built using spring,hibernate and struts2 using JMeter with 250 con current users with ramp up time of 2 seconds. The application was responding back at an average 15 seconds. When i checked my log it seems the database and related operation took 3 seconds and rest all went in struts2 actions and it's processing. The difference is quite huge. I have already upgraded the application to OGNL 3.0.6 and have followed most of the guidelines provided in the link http://struts.apache.org/development/2.x/docs/performance-tuning.html

Has anyone faced the similar issues and what's the solution to make it much quicker(It is quite unbelievable that database with thousands of records responded in less than 3 seconds where as struts2 is taking 12 seconds).

More Update: I just collected Struts2 Logs in TRACE mode. Just have a look at the OGNL and property conversions. It takes close to 18 seconds and the overall stack execution is 23 Seconds)..

[2014-06-05T11:43:45,184+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.InstantiatingNullHandler - Entering nullPropertyValue [target=[com.tastyfood.foody.action.vendor.MealAnnouncementAction@143bc1e6, com.opensymphony.xwork2.DefaultTextProvider@77763f2a], property=struts] [2014-06-05T11:43:45,647+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.InstantiatingNullHandler - Entering nullPropertyValue [target=[com.tastyfood.foody.action.vendor.MealAnnouncementAction@143bc1e6, com.opensymphony.xwork2.DefaultTextProvider@77763f2a], property=org] [2014-06-05T11:43:46,182+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.ognl.accessor.XWorkMapPropertyAccessor - Entering setProperty(ognl.OgnlContext@7bda4cad,org.apache.struts2.util.AttributeMap@152f16eb,newRow,true) [2014-06-05T11:43:46,540+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.ognl.accessor.XWorkMapPropertyAccessor - Entering getProperty (ognl.OgnlContext@53e81e35,

{cityId=1, localityId=1, localityName=Marathahalli},cartSize) [2014-06-05T11:43:47,342+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.InstantiatingNullHandler - Entering nullPropertyValue [target={cityId=1, localityId=1, localityName=Marathahalli}

, property=cartSize] [2014-06-05T11:43:47,734+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.ognl.accessor.XWorkMapPropertyAccessor - Entering getProperty (ognl.OgnlContext@31d96651,org.apache.struts2.util.AttributeMap@53ddcdc7,templateDir) [2014-06-05T11:43:48,518+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.InstantiatingNullHandler - Entering nullPropertyValue [target=[com.tastyfood.foody.action.vendor.MealAnnouncementAction@143bc1e6, com.opensymphony.xwork2.DefaultTextProvider@77763f2a], property=templateDir] [2014-06-05T11:43:49,068+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.InstantiatingNullHandler - Entering nullPropertyValue [target=org.apache.struts2.util.AttributeMap@53ddcdc7, property=templateDir] [2014-06-05T11:43:49,673+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - Retrieving convert for class [class com.opensymphony.xwork2.util.CompoundRoot] and property [(null)] [2014-06-05T11:43:49,942+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - field-level type converter for property [null] = none found [2014-06-05T11:43:50,536+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - global-level type converter for property [null] = none found [2014-06-05T11:43:50,748+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - falling back to default type converter [com.opensymphony.xwork2.conversion.impl.XWorkBasicConverter@2307dd5c] [2014-06-05T11:43:51,355+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.InstantiatingNullHandler - Entering nullPropertyValue [target=[com.tastyfood.foody.action.vendor.MealAnnouncementAction@143bc1e6, com.opensymphony.xwork2.DefaultTextProvider@77763f2a], property=cart] [2014-06-05T11:43:51,593+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - Retrieving convert for class [class com.opensymphony.xwork2.util.CompoundRoot] and property [(null)] [2014-06-05T11:43:52,096+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - field-level type converter for property [null] = none found [2014-06-05T11:43:52,297+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - global-level type converter for property [null] = none found [2014-06-05T11:43:52,965+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - falling back to default type converter [com.opensymphony.xwork2.conversion.impl.XWorkBasicConverter@2307dd5c] [2014-06-05T11:43:53,173+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.ognl.accessor.XWorkMapPropertyAccessor - Entering getProperty (ognl.OgnlContext@31d96651,org.apache.struts2.util.AttributeMap@53ddcdc7,templateDir) [2014-06-05T11:43:53,760+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.InstantiatingNullHandler - Entering nullPropertyValue [target=[com.tastyfood.foody.action.vendor.MealAnnouncementAction@143bc1e6, com.opensymphony.xwork2.DefaultTextProvider@77763f2a], property=templateDir] [2014-06-05T11:43:53,761+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.InstantiatingNullHandler - Entering nullPropertyValue [target=org.apache.struts2.util.AttributeMap@53ddcdc7, property=templateDir] [2014-06-05T11:43:53,769+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - Retrieving convert for class [class com.opensymphony.xwork2.util.CompoundRoot] and property [(null)] [2014-06-05T11:43:54,020+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - field-level type converter for property [null] = none found [2014-06-05T11:43:54,515+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - global-level type converter for property [null] = none found [2014-06-05T11:43:54,739+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - falling back to default type converter [com.opensymphony.xwork2.conversion.impl.XWorkBasicConverter@2307dd5c] [2014-06-05T11:43:54,740+0530] DEBUG [qtp2020886105-153] org.apache.struts2.components.UIBean - Rendering template /template/jquery/a [2014-06-05T11:43:55,631+0530] DEBUG [qtp2020886105-153] org.apache.struts2.components.template.FreemarkerTemplateEngine - Rendering template /template/jquery/a.ftl [2014-06-05T11:43:55,633+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.util.LocalizedTextUtil - Unable to find text for key ' Cart(0)' in class 'com.tastyfood.foody.action.vendor.MealAnnouncementAction' and locale 'en_US' [2014-06-05T11:43:56,253+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.InstantiatingNullHandler - Entering nullMethodResult [2014-06-05T11:43:57,026+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - Retrieving convert for class [class com.opensymphony.xwork2.util.CompoundRoot] and property [(null)] [2014-06-05T11:43:57,266+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - field-level type converter for property [null] = none found [2014-06-05T11:43:57,877+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - global-level type converter for property [null] = none found [2014-06-05T11:43:57,878+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - falling back to default type converter [com.opensymphony.xwork2.conversion.impl.XWorkBasicConverter@2307dd5c] [2014-06-05T11:43:57,878+0530] WARN [qtp2020886105-153] org.apache.struts2.util.TextProviderHelper - The first TextProvider in the ValueStack (com.tastyfood.foody.action.vendor.MealAnnouncementAction) could not locate the message resource with key ' Cart(0)' [2014-06-05T11:43:58,185+0530] WARN [qtp2020886105-153] org.apache.struts2.util.TextProviderHelper - The default value expression ' Cart(0)' was evaluated and did not match a property. The literal value ' Cart(0)' will be used. [2014-06-05T11:43:58,780+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.ognl.accessor.XWorkMapPropertyAccessor - Entering getProperty (ognl.OgnlContext@24b0e8e3,org.apache.struts2.util.AttributeMap@46ea4f39,templateDir) [2014-06-05T11:43:59,058+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.InstantiatingNullHandler - Entering nullPropertyValue [target=[com.tastyfood.foody.action.vendor.MealAnnouncementAction@143bc1e6, com.opensymphony.xwork2.DefaultTextProvider@77763f2a], property=templateDir] [2014-06-05T11:43:59,668+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.InstantiatingNullHandler - Entering nullPropertyValue [target=org.apache.struts2.util.AttributeMap@46ea4f39, property=templateDir] [2014-06-05T11:43:59,913+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - Retrieving convert for class [class com.opensymphony.xwork2.util.CompoundRoot] and property [(null)] [2014-06-05T11:44:00,513+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - field-level type converter for property [null] = none found [2014-06-05T11:44:00,779+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - global-level type converter for property [null] = none found [2014-06-05T11:44:01,358+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - falling back to default type converter [com.opensymphony.xwork2.conversion.impl.XWorkBasicConverter@2307dd5c] [2014-06-05T11:44:01,637+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.InstantiatingNullHandler - Entering nullPropertyValue [target=[com.tastyfood.foody.action.vendor.MealAnnouncementAction@143bc1e6, com.opensymphony.xwork2.DefaultTextProvider@77763f2a], property=cart] [2014-06-05T11:44:02,165+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - Retrieving convert for class [class com.opensymphony.xwork2.util.CompoundRoot] and property [(null)] [2014-06-05T11:44:02,165+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - field-level type converter for property [null] = none found [2014-06-05T11:44:02,165+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - global-level type converter for property [null] = none found [2014-06-05T11:44:02,165+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - falling back to default type converter [com.opensymphony.xwork2.conversion.impl.XWorkBasicConverter@2307dd5c] [2014-06-05T11:44:02,168+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.ognl.accessor.XWorkMapPropertyAccessor - Entering getProperty (ognl.OgnlContext@24b0e8e3,org.apache.struts2.util.AttributeMap@46ea4f39,templateDir) [2014-06-05T11:44:02,404+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.InstantiatingNullHandler - Entering nullPropertyValue [target=[com.tastyfood.foody.action.vendor.MealAnnouncementAction@143bc1e6, com.opensymphony.xwork2.DefaultTextProvider@77763f2a], property=templateDir] [2014-06-05T11:44:03,241+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.InstantiatingNullHandler - Entering nullPropertyValue [target=org.apache.struts2.util.AttributeMap@46ea4f39, property=templateDir] [2014-06-05T11:44:03,487+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - Retrieving convert for class [class com.opensymphony.xwork2.util.CompoundRoot] and property [(null)] [2014-06-05T11:44:03,487+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - field-level type converter for property [null] = none found [2014-06-05T11:44:03,488+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - global-level type converter for property [null] = none found [2014-06-05T11:44:03,488+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - falling back to default type converter [com.opensymphony.xwork2.conversion.impl.XWorkBasicConverter@2307dd5c] [2014-06-05T11:44:03,488+0530] DEBUG [qtp2020886105-153] org.apache.struts2.components.UIBean - Rendering template /template/jquery/a-close [2014-06-05T11:44:03,488+0530] DEBUG [qtp2020886105-153] org.apache.struts2.components.template.FreemarkerTemplateEngine - Rendering template /template/jquery/a-close.ftl [2014-06-05T11:44:03,490+0530] INFO [qtp2020886105-153] Struts Action Logger - out put of the action class com.tastyfood.foody.action.vendor.MealAnnouncementAction is success ****************************************** ENDING THREAD EXECUTION************* [2014-06-05T11:44:03,495+0530] DEBUG [qtp2020886105-153] Struts Action Logger - After calling action: com.tastyfood.foody.action.vendor.MealAnnouncementAction Time taken: 22553 ms

Thanks

Issue Links

• duplicates: WW-4357 Struts2 Property Conversion issue.