[nsoule]

Hello. I’m experiencing long pauses in a hot deploy when I make a Java code change in an app that is deployed to WAS 7. When I make a change to an xhtml file the change is available almost immediately on the server. When I make a Java change, however, I see a ” User initiated module stop operation” and then a “doDestroy” in the console, followed by a pause of over a minute before the app is available again. An FFDC error is displayed in the console, not sure if it’s directly related. Below is a snippet from my console with timestamps showing when I made the Java change (the module stop operation occurs then), and all logged statements up until the start operation occurs for the app again. I’ve also included the contents of the FFDC error log. In WAS 6 Java changes were deployed to the server within a few seconds.

Thanks for any advice you can offer.

-Nate

[4/22/09 10:30:13:492 EDT] 00000003 ApplicationMg I WSVR0227I: User initiated module stop operation requested on Module, PromotionsManagementUI.war, of application, PromotionsManagementUIEAR
[4/22/09 10:30:13:539 EDT] 00000003 servlet I com.ibm.ws.webcontainer.servlet.ServletWrapper doDestroy SRVE0253I: [PromotionsManagementUIEAR] [/PromotionsManagementUI] [uploadServlet]: Destroy successful.
[4/22/09 10:30:56:741 EDT] 00000012 FfdcProvider I com.ibm.ws.ffdc.impl.FfdcProvider logIncident FFDC1003I: FFDC Incident emitted on C:\llbtools\WAS7_Profiles\PromoMgmtUI\logs\ffdc\server1_3cd03cd_09.04.22_10.30.56.7267468645088039999718.txt com.ibm.ejs.sm.client.ui.NLS.getFormattedMessage 375
[4/22/09 10:30:56:741 EDT] 00000012 webcontainer E com.ibm.ws.webcontainer.WebContainer sendUnavailableException Servlet.has.become.temporarily.unavailable.for.service
[4/22/09 10:31:13:585 EDT] 00000003 servlet I com.ibm.ws.webcontainer.servlet.ServletWrapper doDestroy SRVE0253I: [PromotionsManagementUIEAR] [/PromotionsManagementUI] [Blocking Servlet]: Destroy successful.
[4/22/09 10:31:13:600 EDT] 00000003 servlet I com.ibm.ws.webcontainer.servlet.ServletWrapper doDestroy SRVE0253I: [PromotionsManagementUIEAR] [/PromotionsManagementUI] [PromotionStartupServlet]: Destroy successful.
[4/22/09 10:31:13:631 EDT] 00000003 servlet I com.ibm.ws.webcontainer.servlet.ServletWrapper doDestroy SRVE0253I: [PromotionsManagementUIEAR] [/PromotionsManagementUI] [Persistent Faces Servlet]: Destroy successful.
[4/22/09 10:31:13:663 EDT] 00000003 servlet I com.ibm.ws.webcontainer.servlet.ServletWrapper doDestroy SRVE0253I: [PromotionsManagementUIEAR] [/PromotionsManagementUI] [Faces Servlet]: Destroy successful.
[4/22/09 10:31:13:710 EDT] 00000003 ContextEventR I com.icesoft.faces.util.event.servlet.ContextEventRepeater contextDestroyed Servlet Context Name: PromotionsManagementUI.war, Server Info: IBM WebSphere Application Server/7.0
[4/22/09 10:31:13:835 EDT] 00000003 ApplicationMg I WSVR0228I: User initiated module stop operation request completed on Module, PromotionsManagementUI.war, of application, PromotionsManagementUIEAR
[4/22/09 10:31:13:850 EDT] 00000003 ApplicationMg I WSVR0225I: User initiated module start operation requested on Module, PromotionsManagementUI.war, of application, PromotionsManagementUIEAR

[4/22/09 10:30:56:726 EDT] FFDC Exception:java.util.MissingResourceException SourceId:com.ibm.ejs.sm.client.ui.NLS.getFormattedMessage ProbeId:375 Reporter:com.ibm.ejs.sm.client.ui.NLS@34223422
java.util.MissingResourceException: Can’t find resource for bundle com.ibm.ws.webcontainer.resources.Messages, key Servlet.has.become.temporarily.unavailable.for.service.{0}
at java.util.ResourceBundle.getObject(ResourceBundle.java:398)
at java.util.ResourceBundle.getString(ResourceBundle.java:412)
at com.ibm.ejs.sm.client.ui.NLS.getString(NLS.java:353)
at com.ibm.ejs.sm.client.ui.NLS.getFormattedMessage(NLS.java:400)
at com.ibm.ws.webcontainer.WebContainer.sendUnavailableException(WebContainer.java:1388)
at com.ibm.ws.webcontainer.WebContainer.handleRequest(WebContainer.java:831)
at com.ibm.ws.webcontainer.WSWebContainer.handleRequest(WSWebContainer.java:1566)
at com.ibm.ws.webcontainer.channel.WCChannelLink.ready(WCChannelLink.java:175)
at com.ibm.ws.http.channel.inbound.impl.HttpInboundLink.handleDiscrimination(HttpInboundLink.java:455)
at com.ibm.ws.http.channel.inbound.impl.HttpInboundLink.handleNewInformation(HttpInboundLink.java:384)
at com.ibm.ws.http.channel.inbound.impl.HttpInboundLink.ready(HttpInboundLink.java:272)
at com.ibm.ws.tcp.channel.impl.NewConnectionInitialReadCallback.sendToDiscriminators(NewConnectionInitialReadCallback.java:214)
at com.ibm.ws.tcp.channel.impl.NewConnectionInitialReadCallback.complete(NewConnectionInitialReadCallback.java:113)
at com.ibm.ws.tcp.channel.impl.AioReadCompletionListener.futureCompleted(AioReadCompletionListener.java:165)
at com.ibm.io.async.AbstractAsyncFuture.invokeCallback(AbstractAsyncFuture.java:217)
at com.ibm.io.async.AsyncChannelFuture.fireCompletionActions(AsyncChannelFuture.java:161)
at com.ibm.io.async.AsyncFuture.completed(AsyncFuture.java:138)
at com.ibm.io.async.ResultHandler.complete(ResultHandler.java:204)
at com.ibm.io.async.ResultHandler.runEventProcessingLoop(ResultHandler.java:775)
at com.ibm.io.async.ResultHandler$2.run(ResultHandler.java:905)
at com.ibm.ws.util.ThreadPool$Worker.run(ThreadPool.java:1527)

CapturedDataElements begin
arg BEGIN:com.ibm.ejs.sm.client.ui.NLS@34223422
com.ibm.ejs.sm.client.ui.NLS::tc BEGIN:com.ibm.ejs.ras.TraceComponent@7630763
com.ibm.ejs.ras.TraceElement::ivLevel:10
com.ibm.ejs.ras.TraceElement::ivName:com.ibm.ejs.sm.client.ui.NLS
com.ibm.ejs.ras.TraceElement::ivDebugEnabled:false
com.ibm.ejs.ras.TraceElement::ivEventEnabled:false
com.ibm.ejs.ras.TraceElement::ivEntryEnabled:false
com.ibm.ejs.ras.TraceElement::ivDetailEnabled:false
com.ibm.ejs.ras.TraceElement::ivConfigEnabled:false
com.ibm.ejs.ras.TraceElement::ivInfoEnabled:true
com.ibm.ejs.ras.TraceElement::ivServiceEnabled:true
com.ibm.ejs.ras.TraceElement::ivWarningEnabled:true
com.ibm.ejs.ras.TraceElement::ivErrorEnabled:true
com.ibm.ejs.ras.TraceElement::ivFatalEnabled:true
com.ibm.ejs.ras.TraceComponent::defaultMessageFile:com.ibm.ejs.resources.seriousMessages
com.ibm.ejs.ras.TraceComponent::EXTENSION_NAME_DPID:DiagnosticProvider
com.ibm.ejs.ras.TraceComponent::ivDumpEnabled:false
com.ibm.ejs.ras.TraceComponent::ivResourceBundleName:com.ibm.ejs.resources.seriousMessages
com.ibm.ejs.ras.TraceComponent::ivLogger:null
com.ibm.ejs.ras.TraceComponent::ivDiagnosticProviderID:null
com.ibm.ejs.ras.TraceComponent::anyTracingEnabled:false
END:com.ibm.ejs.ras.TraceComponent@7630763

com.ibm.ejs.sm.client.ui.NLS::messages:null
com.ibm.ejs.sm.client.ui.NLS::baseClassPackage:com.ibm.ejs.resources
com.ibm.ejs.sm.client.ui.NLS::bundleName:com.ibm.ws.webcontainer.resources.Messages
com.ibm.ejs.sm.client.ui.NLS::bundle BEGIN:com.ibm.ws.webcontainer.resources.Messages_en@50e650e6
com.ibm.ws.webcontainer.resources.Messages_en@50e650e6
com.ibm.ws.webcontainer.resources.Messages_en::resources BEGIN:[[Ljava.lang.Object;@177a177a
[
[Ljava.lang.Object;@4b344b34 depth limit reached
[Ljava.lang.Object;@4b374b37 depth limit reached
[Ljava.lang.Object;@4b3a4b3a depth limit reached
[Ljava.lang.Object;@4b3d4b3d depth limit reached
[Ljava.lang.Object;@4b404b40 depth limit reached
[Ljava.lang.Object;@4b434b43 depth limit reached
[Ljava.lang.Object;@4b464b46 depth limit reached
[Ljava.lang.Object;@4b494b49 depth limit reached
[Ljava.lang.Object;@4b4c4b4c depth limit reached
[Ljava.lang.Object;@4b4f4b4f depth limit reached
[Ljava.lang.Object;@4b524b52 depth limit reached
[Ljava.lang.Object;@4b554b55 depth limit reached
[Ljava.lang.Object;@4b584b58 depth limit reached
[Ljava.lang.Object;@4b5b4b5b depth limit reached
[Ljava.lang.Object;@4b5e4b5e depth limit reached
[Ljava.lang.Object;@4b614b61 depth limit reached
[Ljava.lang.Object;@4b644b64 depth limit reached
[Ljava.lang.Object;@4b674b67 depth limit reached
[Ljava.lang.Object;@4b6a4b6a depth limit reached
[Ljava.lang.Object;@4b6d4b6d depth limit reached
[Ljava.lang.Object;@4b704b70 depth limit reached
[Ljava.lang.Object;@4b734b73 depth limit reached
[Ljava.lang.Object;@4b764b76 depth limit reached
[Ljava.lang.Object;@4b794b79 depth limit reached
[Ljava.lang.Object;@4b7c4b7c depth limit reached
[Ljava.lang.Object;@4b7f4b7f depth limit reached
[Ljava.lang.Object;@4b824b82 depth limit reached
[Ljava.lang.Object;@4b854b85 depth limit reached
[Ljava.lang.Object;@4b884b88 depth limit reached
[Ljava.lang.Object;@4b8b4b8b depth limit reached
[Ljava.lang.Object;@23ee23ee depth limit reached
[Ljava.lang.Object;@51fa51fa depth limit reached
[Ljava.lang.Object;@51fd51fd depth limit reached
[Ljava.lang.Object;@52005200 depth limit reached
[Ljava.lang.Object;@52035203 depth limit reached
[Ljava.lang.Object;@52065206 depth limit reached
[Ljava.lang.Object;@52095209 depth limit reached
[Ljava.lang.Object;@520c520c depth limit reached
[Ljava.lang.Object;@520f520f depth limit reached
[Ljava.lang.Object;@52125212 depth limit reached
[Ljava.lang.Object;@52155215 depth limit reached
[Ljava.lang.Object;@52185218 depth limit reached
[Ljava.lang.Object;@521b521b depth limit reached
[Ljava.lang.Object;@521e521e depth limit reached
[Ljava.lang.Object;@52215221 depth limit reached
[Ljava.lang.Object;@52245224 depth limit reached
[Ljava.lang.Object;@52275227 depth limit reached
[Ljava.lang.Object;@522a522a depth limit reached
[Ljava.lang.Object;@522d522d depth limit reached
[Ljava.lang.Object;@52305230 depth limit reached
[Ljava.lang.Object;@52335233 depth limit reached
[Ljava.lang.Object;@52365236 depth limit reached
[Ljava.lang.Object;@52395239 depth limit reached
[Ljava.lang.Object;@523c523c depth limit reached
[Ljava.lang.Object;@523f523f depth limit reached
[Ljava.lang.Object;@52425242 depth limit reached
[Ljava.lang.Object;@52455245 depth limit reached
[Ljava.lang.Object;@52485248 depth limit reached
[Ljava.lang.Object;@524b524b depth limit reached
[Ljava.lang.Object;@524e524e depth limit reached
[Ljava.lang.Object;@52515251 depth limit reached
[Ljava.lang.Object;@52545254 depth limit reached
[Ljava.lang.Object;@52575257 depth limit reached
[Ljava.lang.Object;@525a525a depth limit reached
[Ljava.lang.Object;@525d525d depth limit reached
[Ljava.lang.Object;@52605260 depth limit reached
[Ljava.lang.Object;@52635263 depth limit reached
[Ljava.lang.Object;@52665266 depth limit reached
[Ljava.lang.Object;@52695269 depth limit reached
[Ljava.lang.Object;@526c526c depth limit reached
[Ljava.lang.Object;@526f526f depth limit reached
[Ljava.lang.Object;@52725272 depth limit reached
[Ljava.lang.Object;@52755275 depth limit reached
[Ljava.lang.Object;@52785278 depth limit reached
[Ljava.lang.Object;@527b527b depth limit reached
[Ljava.lang.Object;@527e527e depth limit reached
[Ljava.lang.Object;@52815281 depth limit reached
[Ljava.lang.Object;@52845284 depth limit reached
[Ljava.lang.Object;@52875287 depth limit reached
[Ljava.lang.Object;@528a528a depth limit reached
[Ljava.lang.Object;@528d528d depth limit reached
[Ljava.lang.Object;@52905290 depth limit reached
[Ljava.lang.Object;@52935293 depth limit reached
[Ljava.lang.Object;@52965296 depth limit reached
[Ljava.lang.Object;@52995299 depth limit reached
[Ljava.lang.Object;@529c529c depth limit reached
[Ljava.lang.Object;@529f529f depth limit reached
[Ljava.lang.Object;@52a252a2 depth limit reached
[Ljava.lang.Object;@52a552a5 depth limit reached
[Ljava.lang.Object;@52a852a8 depth limit reached
[Ljava.lang.Object;@52ab52ab depth limit reached
[Ljava.lang.Object;@52ae52ae depth limit reached
[Ljava.lang.Object;@52b152b1 depth limit reached
[Ljava.lang.Object;@52b452b4 depth limit reached
[Ljava.lang.Object;@52b752b7 depth limit reached
[Ljava.lang.Object;@52ba52ba depth limit reached
[Ljava.lang.Object;@52bd52bd depth limit reached
[Ljava.lang.Object;@52c052c0 depth limit reached
[Ljava.lang.Object;@52c352c3 depth limit reached
[Ljava.lang.Object;@52c652c6 depth limit reached
[Ljava.lang.Object;@52c952c9 depth limit reached
[Ljava.lang.Object;@52cc52cc depth limit reached
[Ljava.lang.Object;@52cf52cf depth limit reached
[Ljava.lang.Object;@52d252d2 depth limit reached
[Ljava.lang.Object;@52d552d5 depth limit reached
[Ljava.lang.Object;@52d852d8 depth limit reached
[Ljava.lang.Object;@52db52db depth limit reached
[Ljava.lang.Object;@52de52de depth limit reached
[Ljava.lang.Object;@52e152e1 depth limit reached
[Ljava.lang.Object;@52e452e4 depth limit reached
[Ljava.lang.Object;@52e752e7 depth limit reached
[Ljava.lang.Object;@52ea52ea depth limit reached
[Ljava.lang.Object;@52ed52ed depth limit reached
[Ljava.lang.Object;@52f052f0 depth limit reached
[Ljava.lang.Object;@52f352f3 depth limit reached
[Ljava.lang.Object;@52f652f6 depth limit reached
[Ljava.lang.Object;@52f952f9 depth limit reached
[Ljava.lang.Object;@52fc52fc depth limit reached
[Ljava.lang.Object;@52ff52ff depth limit reached
[Ljava.lang.Object;@53025302 depth limit reached
[Ljava.lang.Object;@53055305 depth limit reached
[Ljava.lang.Object;@53085308 depth limit reached
[Ljava.lang.Object;@530b530b depth limit reached
[Ljava.lang.Object;@530e530e depth limit reached
[Ljava.lang.Object;@53115311 depth limit reached
[Ljava.lang.Object;@53145314 depth limit reached
[Ljava.lang.Object;@53175317 depth limit reached
[Ljava.lang.Object;@531a531a depth limit reached
[Ljava.lang.Object;@531d531d depth limit reached
[Ljava.lang.Object;@53205320 depth limit reached
[Ljava.lang.Object;@53235323 depth limit reached
[Ljava.lang.Object;@53265326 depth limit reached
[Ljava.lang.Object;@53295329 depth limit reached
[Ljava.lang.Object;@532c532c depth limit reached
[Ljava.lang.Object;@532f532f depth limit reached
[Ljava.lang.Object;@53325332 depth limit reached
[Ljava.lang.Object;@53355335 depth limit reached
[Ljava.lang.Object;@53385338 depth limit reached
[Ljava.lang.Object;@533b533b depth limit reached
[Ljava.lang.Object;@533e533e depth limit reached
[Ljava.lang.Object;@53415341 depth limit reached
[Ljava.lang.Object;@53445344 depth limit reached
[Ljava.lang.Object;@53475347 depth limit reached
[Ljava.lang.Object;@534a534a depth limit reached
[Ljava.lang.Object;@534d534d depth limit reached
[Ljava.lang.Object;@53505350 depth limit reached
[Ljava.lang.Object;@53535353 depth limit reached
[Ljava.lang.Object;@53565356 depth limit reached
[Ljava.lang.Object;@53595359 depth limit reached
[Ljava.lang.Object;@535c535c depth limit reached
[Ljava.lang.Object;@535f535f depth limit reached
[Ljava.lang.Object;@53625362 depth limit reached
[Ljava.lang.Object;@53655365 depth limit reached
[Ljava.lang.Object;@53685368 depth limit reached
[Ljava.lang.Object;@536b536b depth limit reached
[Ljava.lang.Object;@536e536e depth limit reached
[Ljava.lang.Object;@53715371 depth limit reached
[Ljava.lang.Object;@53745374 depth limit reached
[Ljava.lang.Object;@53775377 depth limit reached
[Ljava.lang.Object;@537a537a depth limit reached
[Ljava.lang.Object;@537d537d depth limit reached
[Ljava.lang.Object;@53805380 depth limit reached
[Ljava.lang.Object;@53835383 depth limit reached
[Ljava.lang.Object;@53865386 depth limit reached
[Ljava.lang.Object;@53895389 depth limit reached
[Ljava.lang.Object;@538c538c depth limit reached
[Ljava.lang.Object;@538f538f depth limit reached
[Ljava.lang.Object;@53925392 depth limit reached
[Ljava.lang.Object;@53955395 depth limit reached
[Ljava.lang.Object;@53985398 depth limit reached
[Ljava.lang.Object;@539b539b depth limit reached
[Ljava.lang.Object;@539e539e depth limit reached
[Ljava.lang.Object;@53a153a1 depth limit reached
[Ljava.lang.Object;@53a453a4 depth limit reached
[Ljava.lang.Object;@53a753a7 depth limit reached
[Ljava.lang.Object;@53aa53aa depth limit reached
[Ljava.lang.Object;@53ad53ad depth limit reached
[Ljava.lang.Object;@53b053b0 depth limit reached
[Ljava.lang.Object;@53b353b3 depth limit reached
[Ljava.lang.Object;@53b653b6 depth limit reached
[Ljava.lang.Object;@53b953b9 depth limit reached
[Ljava.lang.Object;@53bc53bc depth limit reached
[Ljava.lang.Object;@53bf53bf depth limit reached
[Ljava.lang.Object;@53c253c2 depth limit reached
[Ljava.lang.Object;@53c553c5 depth limit reached
[Ljava.lang.Object;@53c853c8 depth limit reached
[Ljava.lang.Object;@53cb53cb depth limit reached
[Ljava.lang.Object;@53ce53ce depth limit reached
[Ljava.lang.Object;@53d153d1 depth limit reached
[Ljava.lang.Object;@53d453d4 depth limit reached
[Ljava.lang.Object;@53d753d7 depth limit reached
[Ljava.lang.Object;@53da53da depth limit reached
[Ljava.lang.Object;@53dd53dd depth limit reached
[Ljava.lang.Object;@53e053e0 depth limit reached
[Ljava.lang.Object;@53e353e3 depth limit reached
[Ljava.lang.Object;@53e653e6 depth limit reached
[Ljava.lang.Object;@53e953e9 depth limit reached
[Ljava.lang.Object;@53ec53ec depth limit reached
[Ljava.lang.Object;@53ef53ef depth limit reached
]
END:[[Ljava.lang.Object;@177a177a

END:com.ibm.ws.webcontainer.resources.Messages_en@50e650e6

END:com.ibm.ejs.sm.client.ui.NLS@34223422

CapturedDataElements end

[Loyal Water]

Nate,
I have asked the dev team to look into this issue and they will get back to you with a reply asap.

[robix]

Hi,

I am also facing similar issue with MyEclipse 8.5 and WAS Express 6.1. I followed the giudeline given in http://www.myeclipseide.com/documentation/quickstarts/websphere6/ for setting up my application and currently using the Exploded deployment.
JSP hot-sync is working fine, but not for the Java Code. Getting the same log and behavior –

[6/4/10 18:52:36:291 EDT] 0000001f ServletWrappe I SRVE0253I: [ElectioNet-NJ5EAR] [/ElectioNet] [/jsp/Activities/DocumentImaging/DocImgDocumentTypes.jsp]: Destroy successful.
[6/4/10 18:52:36:322 EDT] 0000001f ServletWrappe I SRVE0242I: [ElectioNet-NJ5EAR] [/ElectioNet] [/jsp/Activities/DocumentImaging/DocImgDocumentTypes.jsp]: Initialization successful.
[6/4/10 19:01:28:414 EDT] 0000001b ApplicationMg I WSVR0227I: User initiated module stop operation requested on Module, ElectioNet-NJ5.war, of application, ElectioNet-NJ5EAR
[6/4/10 19:01:28:508 EDT] 0000001b ServletWrappe I SRVE0253I: [ElectioNet-NJ5EAR] [/ElectioNet] [com.pcc.enet.cb.service.scheduler.LeafProcessSchedulerServlet]: Destroy successful.

Couldn’t find any reply to Nate’s initial query. Please let me know if there is any suggestion. Thanks.

[Biel]

We’re experiencing the same problem with Websphere 7 and MyEclipse 8.6.1.
The problem seems to happen only when code is changed during an active breakpoint. We would like a response to this post as it’s an urgent matter.

[Biel]

We’re experiencing the same problem with Websphere 7 and MyEclipse 8.6.1.
The problem seems to happen only when code is changed during an active breakpoint. We would like a response to this post as it’s an urgent matter.

[Ram]

gabmaster,
I am unable to replicate this issue at my end.
Can you give some more details regarding the issue?
1. What is the OS you are working on?
2. How big is your application and how many project are there in your current workspace?
3. Can you create a small application in a new workspace and check if the issue persists?

[Author]

Posts