FormHandler -Perfomance Issue

I have created a BackBase4 page which on submit will call a Form Model in GX. This Form model has numerous handlers which is executing specific task. Among the handlers one major handlers is calling one webservice from other application and retrieve the response xml. My problem is that time taken to
retrieve the response in BackBase page is around 9 sec. I have checked the log of execution time of each handler including the webservice handler .It is not taking not more than 3 to 4 second. The delay of 5 sec (average) is during the execution of Below portion.


Apr 25, 2009 3:00:29 PM nl.gx.webmanager.handler.forms.foundation.FormProcessor processForm
INFO: replacing host: foo:-1 to e.test.hari.com, new URL: http://e.test.hari.com
Apr 25, 2009 3:00:29 PM nl.gx.webmanager.handler.forms.foundation.FormHandler doPost
INFO: redirecting to: http://e.test.hari.com
Application time: 18.0690497 seconds
28831.299: [GC [PSYoungGen: 595248K->21763K(635840K)] 1167107K->606190K(1938368K), 0.4373016 secs]
Total time for which application threads were stopped: 0.4398899 seconds
Apr 25, 2009 3:00:35 PM nl.gx.webmanager.handler.forms.foundation.FormHandler doPost
WARNING: Unable to retrieve flow id from the request
Apr 25, 2009 3:00:35 PM nl.gx.webmanager.handler.forms.foundation.FormHandler doPost
WARNING: Unable to retrieve flow id from the request
Apr 25, 2009 3:00:35 PM nl.gx.webmanager.handler.forms.foundation.FormHandler doPost
WARNING: Unable to retrieve flow id from the request

I understand that its taking time to execute the FormHandler. I have tested numerous time and there is a delay each time. Let me know if somebody encounter the same issue or has suggestion to resolve this.

Hi pkragunath,

don't know much about client side BB code but I can tell you with the log..

Apr 25, 2009 3:00:29 PM nl.gx.webmanager.handler.forms.foundation.FormProcessor processForm
INFO: replacing host: foo:-1 to e.test.hari.com, new URL: http://e.test.hari.com
Apr 25, 2009 3:00:29 PM nl.gx.webmanager.handler.forms.foundation.FormHandler doPost
INFO: redirecting to: http://e.test.hari.com

End of a regular form post. The redirect URL is being constructed and the redirect is send. From this log snippet I cannot determine how long execution took but anyway at this point your browser gets its response.

Application time: 18.0690497 seconds
28831.299: [GC [PSYoungGen: 595248K->21763K(635840K)] 1167107K->606190K(1938368K), 0.4373016 secs]
Total time for which application threads were stopped: 0.4398899 seconds

Verbose GC. Looks good, although I hope you don't get too many of them taking .4 seconds. If so I'd be looking into my GC settings.

Apr 25, 2009 3:00:35 PM nl.gx.webmanager.handler.forms.foundation.FormHandler doPost
WARNING: Unable to retrieve flow id from the request
Apr 25, 2009 3:00:35 PM nl.gx.webmanager.handler.forms.foundation.FormHandler doPost
WARNING: Unable to retrieve flow id from the request
Apr 25, 2009 3:00:35 PM nl.gx.webmanager.handler.forms.foundation.FormHandler doPost
WARNING: Unable to retrieve flow id from the request

These are 3 separate illegal posts to the formhandler. When the handler is unable to retrieve a valid flow id it will return directly.

So.. we are looking at 1 valid post that is returned normally, a GC that take a while and 3 invalid but separate posts that are returned invalid. If your client is non responsive during this time it looks like it is doing additional invalid requests that block it? I suggest analyzing what actually goes over the wire using a httpproxy or firebug. You can then combine this information with browser behavior and determine where the problem is.

regards,
Bram