0 Replies Latest reply on Oct 13, 2005 2:37 PM by Jeff Wachhorst

    Startup suspends; GeneratedMethodAccessor47.invoke(..), Dele

    Jeff Wachhorst Newbie

      Hello-

      I'm having a problem when starting up JBoss-4.0.1sp1. I'm running it inside Eclipse3.0.1 which is running MyEclipse3.8.4. JBoss is running with

      j2sdk1.4.2_07. My operating system is Windows XP Pro.

      The problem first appeared after I received an InvocationTargetException which I think was a result of a java.lang.StringIndexOutOfBoundsException that I

      got in a JSP I was working on in a Web Application. I don't think I was able to shut down JBoss cleanly after this but it may have been because I tried to

      shut it down after already shutting it down. (Sorry about that). At any rate, when I started JBoss up again, I was getting the error I describe in this

      post. I have undeployed the Web application that I was working on and then start JBoss but am still getting the error.
      What happens is, I start JBoss then it suspends while trying to startup. I am in Eclipse's Debug perspective. At the time of the suspend, here are the first

      few lines of what I see in my Debug window (It won't let me copy/paste the whole thing so I'm keying in the first few lines):

      jboss4Server [Remote Java Application]
      -- org.jboss.Main at localhost:1154
      ----Thread[DestroyJavaVM] (Running)
      ----Thread [main] (Suspended (exception InvocationTargetException))
      ------GeneratedMethodAccessor47.invoke(Object, Object[]) line: not available
      ------DelegatingMethodAccessorImpl.invoke(Object, Object[]) line: 25
      ------Method.invoke(Object,Object[]) line: 324
      ------ReflectedDispatcher.invoke(Invocation) line: 144
      ------Invocation.dispatch() line: 80
      ------NullInterceptor(AbstractInterceptor).invoke(Invocation) line: 122
      ------Invocation.invoke() line: 74
      ------ModelMBeanOperationIntrceptor.invoke(Invocation) line: 131
      ------Invocation.invoke() line: 74
      ------XMBean(AbstractMBeanInvoker).invoke(String, Object[], String[]) line:249
      ------MBeanServerImpl.invoke(ObjectName, String, Object[], String[]) line: 642



      Here's the contents of the console window at the time of the suspend:

      11:11:14,750 INFO [Server] Starting JBoss (MX MicroKernel)...
      11:11:14,750 INFO [Server] Release ID: JBoss [Zion] 4.0.1sp1 (build: CVSTag=JBoss_4_0_1_SP1 date=200502160314)
      11:11:14,750 INFO [Server] Home Dir: C:\jboss4.0.1\jboss-4.0.1sp1
      11:11:14,750 INFO [Server] Home URL: file:/C:/jboss4.0.1/jboss-4.0.1sp1/
      11:11:14,750 INFO [Server] Library URL: file:/C:/jboss4.0.1/jboss-4.0.1sp1/lib/
      11:11:14,765 INFO [Server] Patch URL: null
      11:11:14,765 INFO [Server] Server Name: default
      11:11:14,765 INFO [Server] Server Home Dir: C:\jboss4.0.1\jboss-4.0.1sp1\server\default
      11:11:14,765 INFO [Server] Server Home URL: file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/
      11:11:14,765 INFO [Server] Server Data Dir: C:\jboss4.0.1\jboss-4.0.1sp1\server\default\data
      11:11:14,765 INFO [Server] Server Temp Dir: C:\jboss4.0.1\jboss-4.0.1sp1\server\default\tmp
      11:11:14,765 INFO [Server] Server Config URL: file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/conf/
      11:11:14,765 INFO [Server] Server Library URL: file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/lib/
      11:11:14,765 INFO [Server] Root Deployment Filename: jboss-service.xml
      11:11:14,765 INFO [Server] Starting General Purpose Architecture (GPA)...
      11:11:15,484 INFO [ServerInfo] Java version: 1.4.2_07,Sun Microsystems Inc.
      11:11:15,484 INFO [ServerInfo] Java VM: Java HotSpot(TM) Client VM 1.4.2_07-b05,Sun Microsystems Inc.
      11:11:15,484 INFO [ServerInfo] OS-System: Windows XP 5.1,x86
      11:11:16,187 INFO [Server] Core system initialized
      11:11:18,500 INFO [Log4jService$URLWatchTimerTask] Configuring from URL: resource:log4j.xml
      11:11:18,640 INFO [WebService] Using RMI server codebase: http://ihelpjeff:8083/
      11:11:18,906 INFO [NamingService] Started jndi bootstrap jnpPort=1099, rmiPort=1098, backlog=50, bindAddress=/0.0.0.0, Client SocketFactory=null, Server

      SocketFactory=org.jboss.net.sockets.DefaultSocketFactory@ad093076
      11:11:25,078 INFO [Embedded] Catalina naming disabled
      11:11:26,203 INFO [Http11Protocol] Initializing Coyote HTTP/1.1 on http-0.0.0.0-8080
      11:11:26,281 INFO [Catalina] Initialization processed in 1063 ms
      11:11:26,281 INFO [StandardService] Starting service jboss.web
      11:11:26,296 INFO [StandardEngine] Starting Servlet Engine: Apache Tomcat/5.0.28
      11:11:26,343 INFO [StandardHost] XML validation disabled
      11:11:26,390 INFO [Catalina] Server startup in 109 ms
      11:11:26,656 INFO [TomcatDeployer] deploy, ctxPath=/invoker, warUrl=file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/deploy/http-invoker.sar/invoker.war/
      11:11:28,218 INFO [TomcatDeployer] deploy, ctxPath=/ws4ee, warUrl=file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/tmp/deploy/tmp54067jboss-ws4ee-exp.war/
      11:11:28,484 INFO [TomcatDeployer] deploy, ctxPath=/, warUrl=file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/deploy/jbossweb-tomcat50.sar/ROOT.war/
      11:11:29,093 INFO [TomcatDeployer] deploy, ctxPath=/jbossmq-httpil, warUrl=file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/deploy/jms/jbossmq-

      httpil.sar/jbossmq-httpil.war/



      In the Debug window I click on 'Resume' and the same suspension comes up 3 more times upon hitting 'Resume'. The fourth time I hit 'Resume', the startup

      proceeds again. Here is the contents of the console window, continuing from the last line above, after startup gets back underway:

      11:15:02,500 INFO [MailService] Mail Service bound to java:/Mail
      11:15:03,328 INFO [RARDeployment] Required license terms exist view the META-INF/ra.xml: file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/deploy/jboss-

      local-jdbc.rar
      11:15:03,593 INFO [RARDeployment] Required license terms exist view the META-INF/ra.xml: file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/deploy/jboss-xa

      -jdbc.rar
      11:15:03,843 INFO [RARDeployment] Required license terms exist view the META-INF/ra.xml: file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/deploy/jms/jms-

      ra.rar
      11:15:04,062 INFO [RARDeployment] Required license terms exist view the META-INF/ra.xml: file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/deploy/mail-

      ra.rar
      11:15:05,609 INFO [WrapperDataSourceService] Bound connection factory for resource adapter for ConnectionManager

      'jboss.jca:name=DefaultDS,service=DataSourceBinding to JNDI name 'java:DefaultDS'
      11:15:05,671 WARN [ConfiguredIdentityLoginModule] Creating LoginModule with no configured password!
      11:15:06,187 INFO [A] Bound to JNDI name: queue/A
      11:15:06,203 INFO [B] Bound to JNDI name: queue/B
      11:15:06,203 INFO [C] Bound to JNDI name: queue/C
      11:15:06,203 INFO [D] Bound to JNDI name: queue/D
      11:15:06,218 INFO [ex] Bound to JNDI name: queue/ex
      11:15:06,281 INFO [testTopic] Bound to JNDI name: topic/testTopic
      11:15:06,281 INFO [securedTopic] Bound to JNDI name: topic/securedTopic
      11:15:06,296 INFO [testDurableTopic] Bound to JNDI name: topic/testDurableTopic
      11:15:06,296 INFO [testQueue] Bound to JNDI name: queue/testQueue
      11:15:06,437 INFO [UILServerILService] JBossMQ UIL service available at : /0.0.0.0:8093
      11:15:06,546 INFO [DLQ] Bound to JNDI name: queue/DLQ
      11:15:07,000 INFO [ConnectionFactoryBindingService] Bound connection factory for resource adapter for ConnectionManager

      'jboss.jca:name=JmsXA,service=ConnectionFactoryBinding to JNDI name 'java:JmsXA'
      11:15:07,546 INFO [WrapperDataSourceService] Bound connection factory for resource adapter for ConnectionManager

      'jboss.jca:name=MySqlDS,service=DataSourceBinding to JNDI name 'java:MySqlDS'
      11:15:07,593 INFO [TomcatDeployer] deploy, ctxPath=/Test1Web, warUrl=file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/deploy/Test1Web.war/
      11:15:07,812 INFO [ContextConfig] Missing application web.xml, using defaults only StandardEngine[jboss.web].StandardHost[localhost].StandardContext

      [/Test1Web]
      11:15:07,906 INFO [TomcatDeployer] deploy, ctxPath=/javaAPIphoto, warUrl=file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/deploy/javaAPIphoto.war/
      11:15:08,203 INFO [TomcatDeployer] deploy, ctxPath=/jmx-console, warUrl=file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/deploy/jmx-console.war/
      11:15:08,484 INFO [TomcatDeployer] deploy, ctxPath=/web-console, warUrl=file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/deploy/management/web-

      console.war/
      11:15:09,968 INFO [Http11Protocol] Starting Coyote HTTP/1.1 on http-0.0.0.0-8080
      11:15:10,906 INFO [ChannelSocket] JK2: ajp13 listening on /0.0.0.0:8009
      11:15:10,953 INFO [JkMain] Jk running ID=0 time=0/157 config=null
      11:15:11,031 INFO [Server] JBoss (MX MicroKernel) [4.0.1sp1 (build: CVSTag=JBoss_4_0_1_SP1 date=200502160314)] Started in 3m:55s:78ms

      Now I shut down the server. Here is the contents of the console window, again picking up from the last line shown (i.e. whose time shows 11:15:11,031):

      11:17:45,734 INFO [Server] LifeThread.run exits!
      11:17:45,750 INFO [Server] Shutting down the JVM now!
      11:17:45,765 INFO [Server] JBoss SHUTDOWN: Undeploying all packages
      11:17:45,796 INFO [TomcatDeployer] undeploy, ctxPath=/web-console, warUrl=file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/deploy/management/web-

      console.war/
      11:17:45,890 INFO [TomcatDeployer] undeploy, ctxPath=/jmx-console, warUrl=file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/deploy/jmx-console.war/
      11:17:45,968 INFO [TomcatDeployer] undeploy, ctxPath=/javaAPIphoto, warUrl=file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/deploy/javaAPIphoto.war/
      11:17:46,000 INFO [TomcatDeployer] undeploy, ctxPath=/Test1Web, warUrl=file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/deploy/Test1Web.war/
      11:17:46,062 INFO [ConnectionFactoryBindingService] Unbound connection factory for resource adapter for ConnectionManager

      'jboss.jca:name=MySqlDS,service=DataSourceBinding from JNDI name 'java:MySqlDS'
      11:17:46,109 INFO [ConnectionFactoryBindingService] Unbound connection factory for resource adapter for ConnectionManager

      'jboss.jca:name=JmsXA,service=ConnectionFactoryBinding from JNDI name 'java:JmsXA'
      11:17:46,203 INFO [ConnectionFactoryBindingService] Unbound connection factory for resource adapter for ConnectionManager

      'jboss.jca:name=DefaultDS,service=DataSourceBinding from JNDI name 'java:DefaultDS'
      11:17:47,171 INFO [HypersonicDatabase] Database standalone closed clean
      11:17:47,234 WARN [DeploymentInfo] Could not delete file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/tmp/deploy/tmp54115mail-ra.rar restart will delete it
      11:17:47,234 WARN [DeploymentInfo] Could not delete file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/tmp/deploy/tmp54114jms-ra.rar restart will delete it
      11:17:47,250 WARN [DeploymentInfo] Could not delete file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/tmp/deploy/tmp54113jboss-xa-jdbc.rar restart will

      delete it
      11:17:47,265 WARN [DeploymentInfo] Could not delete file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/tmp/deploy/tmp54112jboss-local-jdbc.rar restart will

      delete it
      11:17:47,343 INFO [MailService] Mail service 'java:/Mail' removed from JNDI
      11:17:47,687 WARN [DeploymentInfo] Could not delete file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/tmp/deploy/tmp54095uuid-key-generator.sar restart

      will delete it
      11:17:47,687 WARN [DeploymentInfo] Could not delete file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/tmp/deploy/tmp54094jfreechart.jar restart will delete

      it
      11:17:47,687 WARN [DeploymentInfo] Could not delete file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/tmp/deploy/tmp54093jcommon.jar restart will delete it
      11:17:47,687 WARN [DeploymentInfo] Could not delete file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/tmp/deploy/tmp54092console-mgr-classes.jar restart

      will delete it
      11:17:47,703 INFO [TomcatDeployer] undeploy, ctxPath=/jbossmq-httpil, warUrl=file:/C:/jboss4.0.1/jboss-4.0.1sp1/server/default/deploy/jms/jbossmq-

      httpil.sar/jbossmq-httpil.war/
      11:17:47,750 WARN [ServiceController] Ignoring request to stop nonexistent service: null



      At this point I see that the startup process has not ended yet. I hit the 'Resume' button in the Eclipse Debug window. No new lines in the console window

      but I'm seeing this in the Debug window (this is the first few lines of it):

      Thread[JBoss Shutdown Hook] (Suspended(exception InvocationTargetException))
      --GeneratedMethodAccessor75.invoke(Object,Object[])line: not available
      --DelegatingMethodAccessorImpl.invoke(Object, Object[]) line: not available
      --Method.invoke(Object, Object[]) line: 324
      --NotificationListenerProxy.invoke(Object, Method, Object[]) line: 138



      I hit 'Resume' again, some output goes by on the console but is gone by the time shutdown stops. What's left in the console window is this:

      Shutdown message has been posted to the server.
      Server shutdown may take a while - check logfiles for completion

      I think this is a known problem but I haven't found a solution for it mentioned. Can you help?

      Thank you so much in advance!