From issues-return-23950-apmail-struts-issues-archive=struts.apache.org@struts.apache.org Thu Jun 5 10:18:02 2014 Return-Path: X-Original-To: apmail-struts-issues-archive@minotaur.apache.org Delivered-To: apmail-struts-issues-archive@minotaur.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 1045211E21 for ; Thu, 5 Jun 2014 10:18:02 +0000 (UTC) Received: (qmail 15208 invoked by uid 500); 5 Jun 2014 10:18:01 -0000 Delivered-To: apmail-struts-issues-archive@struts.apache.org Received: (qmail 15173 invoked by uid 500); 5 Jun 2014 10:18:01 -0000 Mailing-List: contact issues-help@struts.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@struts.apache.org Delivered-To: mailing list issues@struts.apache.org Received: (qmail 15139 invoked by uid 99); 5 Jun 2014 10:18:01 -0000 Received: from arcas.apache.org (HELO arcas.apache.org) (140.211.11.28) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 05 Jun 2014 10:18:01 +0000 Date: Thu, 5 Jun 2014 10:18:01 +0000 (UTC) From: "Lokesha (JIRA)" To: issues@struts.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Created] (WW-4357) Struts2 Property Conversion issue. MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 Lokesha created WW-4357: --------------------------- Summary: Struts2 Property Conversion issue. Key: WW-4357 URL: https://issues.apache.org/jira/browse/WW-4357 Project: Struts 2 Issue Type: Bug Components: Value Stack Affects Versions: 2.3.16.1 Environment: Jetty, Windows Reporter: Lokesha Fix For: 2.3.16.1 was testing one of my high profile application built using spring,hibernat= e 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. Wh= en i checked my log it seems the database and related operation took 3 seco= nds and rest all went in struts2 actions and it's processing. The differenc= e 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.apa= che.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 r= esponded 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.x= work2.conversion.impl.InstantiatingNullHandler - Entering nullPropertyValue= [target=3D[com.tastyfood.foody.action.vendor.MealAnnouncementAction@143bc1= e6, com.opensymphony.xwork2.DefaultTextProvider@77763f2a], property=3Dstrut= s] [2014-06-05T11:43:45,647+0530] DEBUG [qtp2020886105-153] com.opensymphon= y.xwork2.conversion.impl.InstantiatingNullHandler - Entering nullPropertyVa= lue [target=3D[com.tastyfood.foody.action.vendor.MealAnnouncementAction@143= bc1e6, com.opensymphony.xwork2.DefaultTextProvider@77763f2a], property=3Dor= g] [2014-06-05T11:43:46,182+0530] DEBUG [qtp2020886105-153] com.opensymphon= y.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.opensymp= hony.xwork2.ognl.accessor.XWorkMapPropertyAccessor - Entering getProperty (= ognl.OgnlContext@53e81e35,{cityId=3D1, localityId=3D1, localityName=3DMarat= hahalli},cartSize) [2014-06-05T11:43:47,342+0530] DEBUG [qtp2020886105-153]= com.opensymphony.xwork2.conversion.impl.InstantiatingNullHandler - Enterin= g nullPropertyValue [target=3D{cityId=3D1, localityId=3D1, localityName=3DM= arathahalli}, property=3DcartSize] [2014-06-05T11:43:47,734+0530] DEBUG [qt= p2020886105-153] com.opensymphony.xwork2.ognl.accessor.XWorkMapPropertyAcce= ssor - Entering getProperty (ognl.OgnlContext@31d96651,org.apache.struts2.u= til.AttributeMap@53ddcdc7,templateDir) [2014-06-05T11:43:48,518+0530] DEBUG= [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.InstantiatingN= ullHandler - Entering nullPropertyValue [target=3D[com.tastyfood.foody.acti= on.vendor.MealAnnouncementAction@143bc1e6, com.opensymphony.xwork2.DefaultT= extProvider@77763f2a], property=3DtemplateDir] [2014-06-05T11:43:49,068+053= 0] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.Instan= tiatingNullHandler - Entering nullPropertyValue [target=3Dorg.apache.struts= 2.util.AttributeMap@53ddcdc7, property=3DtemplateDir] [2014-06-05T11:43:49,= 673+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl= .XWorkConverter - Retrieving convert for class [class com.opensymphony.xwor= k2.util.CompoundRoot] and property [(null)] [2014-06-05T11:43:49,942+0530] = DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConv= erter - field-level type converter for property [null] =3D 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] =3D none found [2014-06-05T11:43:50,748+0530] DEBUG [qtp2020886105-1= 53] com.opensymphony.xwork2.conversion.impl.XWorkConverter - falling back t= o default type converter [com.opensymphony.xwork2.conversion.impl.XWorkBasi= cConverter@2307dd5c] [2014-06-05T11:43:51,355+0530] DEBUG [qtp2020886105-15= 3] com.opensymphony.xwork2.conversion.impl.InstantiatingNullHandler - Enter= ing nullPropertyValue [target=3D[com.tastyfood.foody.action.vendor.MealAnno= uncementAction@143bc1e6, com.opensymphony.xwork2.DefaultTextProvider@77763f= 2a], property=3Dcart] [2014-06-05T11:43:51,593+0530] DEBUG [qtp2020886105-1= 53] com.opensymphony.xwork2.conversion.impl.XWorkConverter - Retrieving con= vert for class [class com.opensymphony.xwork2.util.CompoundRoot] and proper= ty [(null)] [2014-06-05T11:43:52,096+0530] DEBUG [qtp2020886105-153] com.op= ensymphony.xwork2.conversion.impl.XWorkConverter - field-level type convert= er for property [null] =3D 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] =3D none found [2014-06-0= 5T11:43:52,965+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conv= ersion.impl.XWorkConverter - falling back to default type converter [com.op= ensymphony.xwork2.conversion.impl.XWorkBasicConverter@2307dd5c] [2014-06-05= T11: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-0= 6-05T11:43:53,760+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.c= onversion.impl.InstantiatingNullHandler - Entering nullPropertyValue [targe= t=3D[com.tastyfood.foody.action.vendor.MealAnnouncementAction@143bc1e6, com= .opensymphony.xwork2.DefaultTextProvider@77763f2a], property=3DtemplateDir]= [2014-06-05T11:43:53,761+0530] DEBUG [qtp2020886105-153] com.opensymphony.= xwork2.conversion.impl.InstantiatingNullHandler - Entering nullPropertyValu= e [target=3Dorg.apache.struts2.util.AttributeMap@53ddcdc7, property=3Dtempl= ateDir] [2014-06-05T11:43:53,769+0530] DEBUG [qtp2020886105-153] com.opensy= mphony.xwork2.conversion.impl.XWorkConverter - Retrieving convert for class= [class com.opensymphony.xwork2.util.CompoundRoot] and property [(null)] [2= 014-06-05T11:43:54,020+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwo= rk2.conversion.impl.XWorkConverter - field-level type converter for propert= y [null] =3D 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] =3D none found [2014-06-05T11:43:54,739= +0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XW= orkConverter - falling back to default type converter [com.opensymphony.xwo= rk2.conversion.impl.XWorkBasicConverter@2307dd5c] [2014-06-05T11:43:54,740+= 0530] DEBUG [qtp2020886105-153] org.apache.struts2.components.UIBean - Rend= ering template /template/jquery/a [2014-06-05T11:43:55,631+0530] DEBUG [qtp= 2020886105-153] org.apache.struts2.components.template.FreemarkerTemplateEn= gine - Rendering template /template/jquery/a.ftl [2014-06-05T11:43:55,633+0= 530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.util.LocalizedTextUt= il - Unable to find text for key ' Cart(0)' in class 'com.tastyfood.foody.a= ction.vendor.MealAnnouncementAction' and locale 'en_US' [2014-06-05T11:43:5= 6,253+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.im= pl.InstantiatingNullHandler - Entering nullMethodResult [2014-06-05T11:43:5= 7,026+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.im= pl.XWorkConverter - Retrieving convert for class [class com.opensymphony.xw= ork2.util.CompoundRoot] and property [(null)] [2014-06-05T11:43:57,266+0530= ] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkCo= nverter - field-level type converter for property [null] =3D none found [20= 14-06-05T11:43:57,877+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwor= k2.conversion.impl.XWorkConverter - global-level type converter for propert= y [null] =3D 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.XWorkBa= sicConverter@2307dd5c] [2014-06-05T11:43:57,878+0530] WARN [qtp2020886105-1= 53] org.apache.struts2.util.TextProviderHelper - The first TextProvider in = the ValueStack (com.tastyfood.foody.action.vendor.MealAnnouncementAction) c= ould not locate the message resource with key ' Cart(0)' [2014-06-05T11:43:= 58,185+0530] WARN [qtp2020886105-153] org.apache.struts2.util.TextProviderH= elper - The default value expression ' Cart(0)' was evaluated and did not m= atch a property. The literal value ' Cart(0)' will be used. [2014-06-05T11:= 43:58,780+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.ognl.acce= ssor.XWorkMapPropertyAccessor - Entering getProperty (ognl.OgnlContext@24b0= e8e3,org.apache.struts2.util.AttributeMap@46ea4f39,templateDir) [2014-06-05= T11:43:59,058+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conve= rsion.impl.InstantiatingNullHandler - Entering nullPropertyValue [target=3D= [com.tastyfood.foody.action.vendor.MealAnnouncementAction@143bc1e6, com.ope= nsymphony.xwork2.DefaultTextProvider@77763f2a], property=3DtemplateDir] [20= 14-06-05T11:43:59,668+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwor= k2.conversion.impl.InstantiatingNullHandler - Entering nullPropertyValue [t= arget=3Dorg.apache.struts2.util.AttributeMap@46ea4f39, property=3DtemplateD= ir] [2014-06-05T11:43:59,913+0530] DEBUG [qtp2020886105-153] com.opensympho= ny.xwork2.conversion.impl.XWorkConverter - Retrieving convert for class [cl= ass 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 [n= ull] =3D none found [2014-06-05T11:44:00,779+0530] DEBUG [qtp2020886105-153= ] com.opensymphony.xwork2.conversion.impl.XWorkConverter - global-level typ= e converter for property [null] =3D none found [2014-06-05T11:44:01,358+053= 0] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkC= onverter - 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.Instant= iatingNullHandler - Entering nullPropertyValue [target=3D[com.tastyfood.foo= dy.action.vendor.MealAnnouncementAction@143bc1e6, com.opensymphony.xwork2.D= efaultTextProvider@77763f2a], property=3Dcart] [2014-06-05T11:44:02,165+053= 0] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkC= onverter - 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] =3D none found [2014-06-05T= 11:44:02,165+0530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conver= sion.impl.XWorkConverter - global-level type converter for property [null] = =3D none found [2014-06-05T11:44:02,165+0530] DEBUG [qtp2020886105-153] com= .opensymphony.xwork2.conversion.impl.XWorkConverter - falling back to defau= lt type converter [com.opensymphony.xwork2.conversion.impl.XWorkBasicConver= ter@2307dd5c] [2014-06-05T11:44:02,168+0530] DEBUG [qtp2020886105-153] com.= opensymphony.xwork2.ognl.accessor.XWorkMapPropertyAccessor - Entering getPr= operty (ognl.OgnlContext@24b0e8e3,org.apache.struts2.util.AttributeMap@46ea= 4f39,templateDir) [2014-06-05T11:44:02,404+0530] DEBUG [qtp2020886105-153] = com.opensymphony.xwork2.conversion.impl.InstantiatingNullHandler - Entering= nullPropertyValue [target=3D[com.tastyfood.foody.action.vendor.MealAnnounc= ementAction@143bc1e6, com.opensymphony.xwork2.DefaultTextProvider@77763f2a]= , property=3DtemplateDir] [2014-06-05T11:44:03,241+0530] DEBUG [qtp20208861= 05-153] com.opensymphony.xwork2.conversion.impl.InstantiatingNullHandler - = Entering nullPropertyValue [target=3Dorg.apache.struts2.util.AttributeMap@4= 6ea4f39, property=3DtemplateDir] [2014-06-05T11:44:03,487+0530] DEBUG [qtp2= 020886105-153] com.opensymphony.xwork2.conversion.impl.XWorkConverter - Ret= rieving 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 t= ype converter for property [null] =3D none found [2014-06-05T11:44:03,488+0= 530] DEBUG [qtp2020886105-153] com.opensymphony.xwork2.conversion.impl.XWor= kConverter - global-level type converter for property [null] =3D none found= [2014-06-05T11:44:03,488+0530] DEBUG [qtp2020886105-153] com.opensymphony.= xwork2.conversion.impl.XWorkConverter - falling back to default type conver= ter [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-0= 5T11:44:03,488+0530] DEBUG [qtp2020886105-153] org.apache.struts2.component= s.template.FreemarkerTemplateEngine - Rendering template /template/jquery/a= -close.ftl [2014-06-05T11:44:03,490+0530] INFO [qtp2020886105-153] Struts A= ction Logger - out put of the action class com.tastyfood.foody.action.vendo= r.MealAnnouncementAction is success ***************************************= *** ENDING THREAD EXECUTION************* [2014-06-05T11:44:03,495+0530] DEB= UG [qtp2020886105-153] Struts Action Logger - After calling action: com.tas= tyfood.foody.action.vendor.MealAnnouncementAction Time taken: 22553 ms Thanks -- This message was sent by Atlassian JIRA (v6.2#6252)