Re: Performance of Shark
Vladimir Puskas <[email protected]>
| Newsgroups | gmane.comp.java.enhydra.shark |
|---|---|
| Message-ID | <[email protected]> |
Hello Neville Can you be more specific, what's wrong with Shark 1.1 in JBoss? How do you deploy it there? We have had report that repackaging DODS jars into one helps [1], but I am curious why don't we have same problem on Tomcat or JOnAS. As performance issue goes, Shark can hide *many* operations behind one method call [1]. It depends greatly on your XPDLs and usage scenario. Version 1.1 should be quite faster than 1.0, especially on XPDL handling... Nevertheless, we're preparing time measuring and reporting add-on for Shark, that could shed some light on your problem too. Attachment contains excerpt from log file made by this component (for all methods lasting more than 120 ms). [1] http://mail-archive.objectweb.org/shark/2005-01/msg00195.html [2] http://mail-archive.objectweb.org/shark/2005-03/msg00016.html regards -- Vlada you wrote: > Hi List, > > I'm having a slight performance issue, and I'm not sure if the speed I'm > getting is right. > > Here's the Setup > > 3 Servers > > Srv Name Function Running on > ----------------------------------------------------------- > MVC MVC Model View Controller Jboss 4.0.0, Windows 2000 > SHK Shark Workflow Server Tomcat 5.5.9, Windows 2000 > ESB ESB MDB's and message Q's Jboss 4.0.0, Windows 2003 > Server > > SHK Used to run on Jboss 4.0.0, but since Shark 1.1, the Shark Library does > not start up in Jboss. > > Request > ------- > User Clicks for a Page, this is what Happens : > 1) MVC Contacts SHK via our own comms method > 2) SHK Loads the Package if it's not Loaded, if it needs to load it > generates the required Participant Mappings. > 3) SHK Starts a new User Process, with the appropriate request variables > placed on the context map. > 4) SHK in this process calls a wrapper to send a request to MDB For the > required Information. > 5) SHK Waits for the next Activity, or if the process comes to a end will > return. > 6) SHK Sends a updated message back to the MVC. > > Some Timing for as Request > Run 1 ( No Package Loaded yet, i.e. First Run ) > ----- > MVC Report > > <!-- Report on Shark > MVC Shark Proxy request Time = 17672ms > > Shark Message Received: > Logon Time = 0ms > Request Time = 16703ms > Get Next Activity Time = 515ms > Logoff Time = 0ms > ------------------------------------------------ > Total Time = 17218ms > > Total Wrapper Time = 8906ms > --> > > In Short: > MVC - SHK Communication & Transfer = 454ms > SHK Process Time = 7797 > SHK - MDB Wrapper Time = 8906ms > SHK Wait next Activity = 515ms > > Run 2 ( Package Loaded i.e. Just need to start the process and go ) > ----- > MVC Report > > <!-- Report on Shark > > MVC Shark Proxy request Time = 2907ms > > Shark Message Received: > Logon Time = 0ms > Request Time = 2312ms > Get Next Activity Time = 516ms > Logoff Time = 0ms > ------------------------------------------------ > Total Time = 2828ms > > Total Wrapper Time = 484ms > --> > > In Short: > MVC - SHK Communication & Transfer = 79ms > SHK Process Time = 1828 > SHK - MDB Wrapper Time = 484ms > SHK Wait next Activity = 516ms > > Run 3 > ----- > <!-- Report on Shark > > MVC Shark Proxy request Time = 2875ms > > Shark Message Received: > Logon Time = 16ms > Request Time = 2250ms > Get Next Activity Time = 531ms > Logoff Time = 0ms > ------------------------------------------------ > Total Time = 2797ms > > Total Wrapper Time = 203ms > --> > > In Short: > MVC - SHK Communication & Transfer = 78ms > SHK Process Time = 2047 > SHK - MDB Wrapper Time = 203ms > SHK Wait next Activity = 531ms > > > > As you can see Shark Process Takes up the most time here, and these results > are with me on the system only, we have a dev team of 5, and when we are all > on the system, the very same process I have run above goes up to about 10 > 000ms. > > The targeted amount of users is approx 500 concurrent .... > > > Thanks for any Input into this .. > > > Neville van Deventer
tp.log
(text/x-log, 5.6 KB)
2005-07-22 09:01:56,146: [main], INFO, TimeProfiler: SharkConnectionImpl.connect took 145 millis for [userId=admin connected at 1] 2005-07-22 09:01:57,096: [main], INFO, TimeProfiler: SharkConnectionImpl.createProcess took 950 millis for [userId=admin connected at 1] 2005-07-22 09:01:57,956: [main], INFO, TimeProfiler: WfProcessWrapper.start took 836 millis for [key=1_test_bs_domath] 2005-07-22 09:01:58,200: [main], INFO, TimeProfiler: WfProcessWrapper.start took 169 millis for [key=2_test_bs_domath] 2005-07-22 09:01:58,454: [main], INFO, TimeProfiler: WfProcessWrapper.start took 178 millis for [key=3_test_bs_domath] 2005-07-22 09:01:58,708: [main], INFO, TimeProfiler: WfProcessWrapper.start took 176 millis for [key=4_test_bs_domath] 2005-07-22 09:02:02,681: [Thread-0], INFO, TimeProfiler: ToolAgentManagerImpl.invokeApplication took 3098 millis for [ actId=13_3_test_bs_domath_arbitrary_math_operation] 2005-07-22 09:02:13,072: [Thread-0], INFO, TimeProfiler: ToolAgentManagerImpl.invokeApplication took 10370 millis for [ actId=14_3_test_bs_domath_waiting] 2005-07-22 09:02:13,383: [Thread-0], INFO, TimeProfiler: WfActivityWrapper.complete took 14337 millis for [pId=3_test_bs_domath,aId=9_3_test_bs_domath_enter_various_parameters] 2005-07-22 09:02:14,178: [Thread-0], INFO, TimeProfiler: WfActivityWrapper.complete took 730 millis for [pId=2_test_bs_domath,aId=5_2_test_bs_domath_enter_math_parameters] 2005-07-22 09:02:14,560: [Thread-0], INFO, TimeProfiler: WfActivityWrapper.complete took 310 millis for [pId=1_test_bs_domath,aId=2_1_test_bs_domath_enter_math_parameters] 2005-07-22 09:02:16,387: [Thread-0], INFO, TimeProfiler: ToolAgentManagerImpl.invokeApplication took 1754 millis for [ actId=26_4_test_bs_domath_arbitrary_math_operation] 2005-07-22 09:02:26,411: [Thread-0], INFO, TimeProfiler: ToolAgentManagerImpl.invokeApplication took 10012 millis for [ actId=27_4_test_bs_domath_waiting] 2005-07-22 09:02:26,606: [Thread-0], INFO, TimeProfiler: WfActivityWrapper.complete took 11991 millis for [pId=4_test_bs_domath,aId=12_4_test_bs_domath_enter_various_parameters] 2005-07-22 09:02:26,932: [Thread-0], INFO, TimeProfiler: WfActivityWrapper.complete took 258 millis for [pId=3_test_bs_domath,aId=8_3_test_bs_domath_enter_math_parameters] 2005-07-22 09:02:28,650: [Thread-0], INFO, TimeProfiler: ToolAgentManagerImpl.invokeApplication took 1622 millis for [ actId=34_2_test_bs_domath_arbitrary_math_operation] 2005-07-22 09:02:38,671: [Thread-0], INFO, TimeProfiler: ToolAgentManagerImpl.invokeApplication took 10010 millis for [ actId=35_2_test_bs_domath_waiting] 2005-07-22 09:02:38,912: [Thread-0], INFO, TimeProfiler: WfActivityWrapper.complete took 11916 millis for [pId=2_test_bs_domath,aId=6_2_test_bs_domath_enter_various_parameters] 2005-07-22 09:02:39,272: [Thread-0], INFO, TimeProfiler: WfActivityWrapper.complete took 242 millis for [pId=4_test_bs_domath,aId=11_4_test_bs_domath_enter_math_parameters] 2005-07-22 09:02:40,630: [Thread-0], INFO, TimeProfiler: ToolAgentManagerImpl.invokeApplication took 1290 millis for [ actId=42_1_test_bs_domath_arbitrary_math_operation] 2005-07-22 09:02:50,651: [Thread-0], INFO, TimeProfiler: ToolAgentManagerImpl.invokeApplication took 10006 millis for [ actId=43_1_test_bs_domath_waiting] 2005-07-22 09:02:50,819: [Thread-0], INFO, TimeProfiler: WfActivityWrapper.complete took 11495 millis for [pId=1_test_bs_domath,aId=3_1_test_bs_domath_enter_various_parameters] 2005-07-22 09:02:51,366: [Thread-0], INFO, TimeProfiler: WfActivityWrapper.complete took 267 millis for [pId=2_test_bs_domath,aId=20_2_test_bs_domath_see_first_result] 2005-07-22 09:02:51,662: [Thread-0], INFO, TimeProfiler: WfAssignmentWrapper.set_accepted_status took 125 millis for [pId=3_test_bs_domath,aId=15_3_test_bs_domath_see_second_result-> un=admin] 2005-07-22 09:02:51,982: [Thread-0], INFO, TimeProfiler: WfActivityWrapper.container took 207 millis for [pId=3_test_bs_domath,aId=33_3_test_bs_domath_see_first_result] 2005-07-22 09:02:52,287: [Thread-0], INFO, TimeProfiler: WfAssignmentWrapper.set_accepted_status took 218 millis for [pId=3_test_bs_domath,aId=33_3_test_bs_domath_see_first_result-> un=admin] 2005-07-22 09:02:52,622: [Thread-0], INFO, TimeProfiler: WfActivityWrapper.complete took 310 millis for [pId=3_test_bs_domath,aId=33_3_test_bs_domath_see_first_result] 2005-07-22 09:02:53,028: [Thread-0], INFO, TimeProfiler: WfActivityWrapper.complete took 268 millis for [pId=1_test_bs_domath,aId=25_1_test_bs_domath_see_first_result] 2005-07-22 09:02:53,338: [Thread-0], INFO, TimeProfiler: WfActivityWrapper.complete took 259 millis for [pId=4_test_bs_domath,aId=28_4_test_bs_domath_see_second_result] 2005-07-22 09:02:53,567: [Thread-0], INFO, TimeProfiler: ToolAgentManagerImpl.invokeApplication took 151 millis for [ actId=57_1_test_bs_domath_send_mail2] 2005-07-22 09:02:53,703: [Thread-0], INFO, TimeProfiler: WfActivityWrapper.complete took 307 millis for [pId=1_test_bs_domath,aId=53_1_test_bs_domath_enter_mail_parameters1] 2005-07-22 09:02:53,915: [Thread-0], INFO, TimeProfiler: WfActivityWrapper.complete took 161 millis for [pId=3_test_bs_domath,aId=50_3_test_bs_domath_enter_mail_parameters1] 2005-07-22 09:02:54,120: [Thread-0], INFO, TimeProfiler: WfActivityWrapper.complete took 161 millis for [pId=4_test_bs_domath,aId=56_4_test_bs_domath_enter_mail_parameters1] 2005-07-22 09:02:54,339: [Thread-0], INFO, TimeProfiler: WfActivityWrapper.complete took 164 millis for [pId=2_test_bs_domath,aId=47_2_test_bs_domath_enter_mail_parameters1] 2005-07-22 09:02:54,581: [Thread-0], INFO, TimeProfiler: WfActivityWrapper.complete took 178 millis for [pId=1_test_bs_domath,aId=58_1_test_bs_domath_enter_mail_parameters2]
message-footer.txt
(text/plain, 271 B)
-- You receive this message as a subscriber of the [email protected] mailing list. To unsubscribe: mailto:[email protected] For general help: mailto:[email protected]?subject=help ObjectWeb mailing lists service home page: http://www.objectweb.org/wws