Startup suspends; GeneratedMethodAccessor47.invoke(..), Dele
jwach366 Oct 13, 2005 2:37 PMHello-
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!