Ajax and Pageflow very slow
pietermartin Nov 28, 2007 5:45 AMHi,
I am using the pageflow and normal s:decorate with ajax pattern as in the examples.
On the onblur event huge amounts of code seems to execute (seen in server.log) which takes so long that subsequest onblur events gets a concurrent problem
summary=(The conversation ended, timed out or was processing another request), detail=(The conversation ended, timed out or was processing another request)
Here is some of the logs from server.log generated on a single onblur ajax event.
2007-11-28 12:24:16,052 DEBUG [org.ajax4jsf.webapp.BaseFilter] Filter start request processing at 11/28/07 12:24 PM for uri: /web/signyouup/capturePhoneDetails.seam 2007-11-28 12:24:16,052 DEBUG [org.ajax4jsf.webapp.BaseFilter] Incoming request has Content-Type header with character encodin g UTF-8 2007-11-28 12:24:16,052 DEBUG [org.ajax4jsf.webapp.BaseFilter] Filter request output to XML 2007-11-28 12:24:16,052 DEBUG [org.ajax4jsf.webapp.BaseXMLFilter] XML filter service start processing request 2007-11-28 12:24:16,053 DEBUG [org.ajax4jsf.event.AjaxPhaseListener] Process before phase RESTORE_VIEW 1 2007-11-28 12:24:16,053 DEBUG [org.jboss.seam.contexts.FacesLifecycle] >>> Begin JSF request 2007-11-28 12:24:16,053 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.transaction.transaction 2007-11-28 12:24:16,053 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.transaction.transaction 2007-11-28 12:24:16,053 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.transaction.transaction 2007-11-28 12:24:16,053 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.events 2007-11-28 12:24:16,053 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.events 2007-11-28 12:24:16,053 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.events 2007-11-28 12:24:16,053 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.events 2007-11-28 12:24:16,053 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.events 2007-11-28 12:24:16,053 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.events 2007-11-28 12:24:16,053 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preSetVariable.org.jboss.seam.trans action.transaction .... same output for hundreds of lines 2007-11-28 12:24:16,110 DEBUG [org.jboss.seam.Component] seam component not found: org.jboss.seam.transaction.required 2007-11-28 12:24:16,110 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.bpm.processInstance 2007-11-28 12:24:16,110 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.bpm.processInstance 2007-11-28 12:24:16,110 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.bpm.processInstance 2007-11-28 12:24:16,110 DEBUG [org.jboss.seam.util.Naming] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp .interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} 2007-11-28 12:24:16,111 DEBUG [org.jboss.seam.util.Naming] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp .interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} 2007-11-28 12:24:16,111 DEBUG [org.jboss.seam.Component] seam component not found: org.jboss.seam.framework.required 2007-11-28 12:24:16,111 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.bpm.processInstance 2007-11-28 12:24:16,111 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.bpm.processInstance 2007-11-28 12:24:16,111 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.bpm.processInstance 2007-11-28 12:24:16,111 DEBUG [org.jboss.seam.util.Naming] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp .interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} 2007-11-28 12:24:16,111 DEBUG [org.jboss.seam.util.Naming] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp .interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} 2007-11-28 12:24:16,111 DEBUG [org.jboss.seam.Component] seam component not found: org.jboss.seam.web.required 2007-11-28 12:24:16,111 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.bpm.processInstance 2007-11-28 12:24:16,111 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.bpm.processInstance 2007-11-28 12:24:16,111 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.bpm.processInstance 2007-11-28 12:24:16,111 DEBUG [org.jboss.seam.util.Naming] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp .interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} .... same output for hundreds of lines 2007-11-28 12:24:16,751 DEBUG [org.jbpm.svc.Services] executing default save operations 2007-11-28 12:24:16,751 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance 2007-11-28 12:24:16,751 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service. 2007-11-28 12:24:16,751 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@9 05b91' 2007-11-28 12:24:16,751 DEBUG [org.jbpm.svc.Services] executing default save operations 2007-11-28 12:24:16,751 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance 2007-11-28 12:24:16,751 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service. 2007-11-28 12:24:16,751 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@9 05b91' 2007-11-28 12:24:16,751 DEBUG [org.jbpm.svc.Services] executing default save operations 2007-11-28 12:24:16,752 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance 2007-11-28 12:24:16,752 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service. 2007-11-28 12:24:16,752 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@9 05b91' .... same output for hundreds of lines 2007-11-28 12:24:19,225 DEBUG [org.ajax4jsf.event.AjaxPhaseListener] Process after phase RENDER_RESPONSE 6 2007-11-28 12:24:19,225 DEBUG [org.ajax4jsf.webapp.BaseXMLFilter] Process response to well-formed XML for AJAX XMLHttpRequest parser 2007-11-28 12:24:19,226 DEBUG [org.ajax4jsf.webapp.tidy.TidyParser] Print output as XML 2007-11-28 12:24:19,226 DEBUG [org.ajax4jsf.webapp.tidy.TidyParser] Message for HTML parsing : missing <!DOCTYPE> declaration 2007-11-28 12:24:19,226 DEBUG [org.ajax4jsf.webapp.tidy.TidyParser] Message for HTML parsing : inserting missing 'title' eleme nt 2007-11-28 12:24:19,226 DEBUG [org.ajax4jsf.webapp.tidy.TidyParser] Message for HTML parsing : meta isn't allowed in <body> el ements 2007-11-28 12:24:19,227 DEBUG [org.ajax4jsf.webapp.tidy.TidyParser] Message for HTML parsing : meta isn't allowed in <body> el ements 2007-11-28 12:24:19,228 DEBUG [org.ajax4jsf.webapp.BaseXMLFilter] Parsing html total time 2ms 2007-11-28 12:24:19,228 DEBUG [org.ajax4jsf.webapp.BaseFilter] Finished request processing total time 721ms for uri: /web/sign youup/capturePhoneDetails.seam
This single ajax onblur request took 721ms.
It seems that Seam needs to work far to hard during the ajax request. Going to the bpm hundreds of times instantiating components hundreds of time etc.
I note that the dvd example does not use ajax.
Is this a know issue?
I am using seam-2.0.0.GA and jboss-4.2.2.GA
Cheers
Pieter