Hi,
As we're debugging performance issues on our webapp, we were surprised to see that our JSF backing beans action being executed twice and we can see the RENDER_RESPONSE phase get invoked twice for every page request as well:
2009-05-19 02:15:48,416 INFO com.proace.util.ConsoleListener - BEFORE - RENDER_RESPONSE 6 : /dummy.jsp [
http://isa-stage-doc-0101.sea3.proace.com:8090/console/dummy.jsp] http-8090-1~12 com.sun.faces.context.FacesContextImpl@c3358b [com.sun.faces.renderKitImplForRequest, com.sun.faces.managedBeanStack, com.sun.faces.FacesContextImpl, com.exade.vcp.Filter.done, com.sun.faces.INVOCATION_PATH, org.restfaces.ISNOTPOSTBACK, com.sun.faces.ExternalContextImpl, ajaxContext, com.exade.vcp.Filter.ResponseWrapper]
2009-05-19 02:15:48,417 INFO com.proace.util.ConsoleListener - BEFORE - RENDER_RESPONSE 6 : /dummy.jsp [
http://isa-stage-doc-0101.sea3.proace.com:8090/console/dummy.jsp] http-8090-1~12 com.sun.faces.context.FacesContextImpl@c3358b [com.sun.faces.renderKitImplForRequest, com.sun.faces.managedBeanStack, com.sun.faces.FacesContextImpl, com.exade.vcp.Filter.done, com.sun.faces.INVOCATION_PATH, org.restfaces.ISNOTPOSTBACK, com.sun.faces.ExternalContextImpl, ajaxContext, com.exade.vcp.Filter.ResponseWrapper]
2009-05-19 02:15:49,826 INFO com.proace.util.ConsoleListener - AFTER - RENDER_RESPONSE 6 : /dummy.jsp [
http://isa-stage-doc-0101.sea3.proace.com:8090/console/dummy.jsp] http-8090-1~12 com.sun.faces.context.FacesContextImpl@c3358b [org/richfaces/renderkit/html/scripts/skinning.js, org.ajax4jsf.framework.HEAD_EVENTS_LIST, org.ajax4jsf.framework.HEADER_PROCESSED, /org/richfaces/skin.xcss, org.ajax4jsf.context.ViewResources$1, org.ajax4jsf.component.QueueRegistry, com.sun.faces.renderKitImplForRequest, com.sun.faces.managedBeanStack, com.exade.vcp.Filter.done, com.sun.faces.FacesContextImpl, javax.servlet.jsp.jstl.fmt.locale.request, org/richfaces/renderkit/html/css/basic_both.xcss, org/richfaces/renderkit/html/css/extended_both.xcss, com.sun.faces.INVOCATION_PATH, org.restfaces.ISNOTPOSTBACK, dummy, com.sun.faces.ExternalContextImpl, org.ajax4jsf.skin.HASH_CODES_MAP, org.ajax4jsf.VIEW_ID, ajaxContext, com.exade.vcp.Filter.ResponseWrapper]
2009-05-19 02:15:49,826 INFO com.proace.util.ConsoleListener - null
org.restfaces.application.RestFacesPhaseListener_at_1de45e2
org.ajax4jsf.event.AjaxPhaseListener_at_1be2893
com.proace.util.ConsoleListener_at_b3f9b8
org.restfaces.application.RestFacesPhaseListener_at_39c8c1
org.ajax4jsf.event.AjaxPhaseListener_at_1ab2b55
com.proace.util.ConsoleListener_at_4b11e9
com.sun.rave.web.ui.appbase.faces.ViewHandlerImpl_at_54824
2009-05-19 02:15:49,826 INFO com.proace.util.ConsoleListener - AFTER - RENDER_RESPONSE 6 : /dummy.jsp [
http://isa-stage-doc-0101.sea3.proace.com:8090/console/dummy.jsp] http-8090-1~12 com.sun.faces.context.FacesContextImpl@c3358b [org/richfaces/renderkit/html/scripts/skinning.js, org.ajax4jsf.framework.HEAD_EVENTS_LIST, org.ajax4jsf.framework.HEADER_PROCESSED, /org/richfaces/skin.xcss, org.ajax4jsf.context.ViewResources$1, org.ajax4jsf.component.QueueRegistry, com.sun.faces.renderKitImplForRequest, com.sun.faces.managedBeanStack, com.exade.vcp.Filter.done, com.sun.faces.FacesContextImpl, javax.servlet.jsp.jstl.fmt.locale.request, org/richfaces/renderkit/html/css/basic_both.xcss, org/richfaces/renderkit/html/css/extended_both.xcss, com.sun.faces.INVOCATION_PATH, org.restfaces.ISNOTPOSTBACK, dummy, com.sun.faces.ExternalContextImpl, org.ajax4jsf.skin.HASH_CODES_MAP, org.ajax4jsf.VIEW_ID, ajaxContext, com.exade.vcp.Filter.ResponseWrapper]
2009-05-19 02:16:48,910 INFO org.apache.tiles.access.TilesAccess - Removing TilesContext for context: org.apache.tiles.web.util.ServletContextAdapter
The above log is generated by the custom phase listener that listen to all phases of the lifecycle, clearly show that the render response phase is executed twice, could you provide information of why this is happening?
[Message sent by forum member 'ngchihou' (ngchihou)]
http://forums.java.net/jive/thread.jspa?messageID=346754