]
Axel Wathne commented on JBIDE-14027:
-------------------------------------
[~mmalina] With 3.0.0.Beta3-v20140630-1657-B543 I get the same error. But that was built
yesterday morning i guess? So I will try again tomorrow and see if a v20140701 build is
available.
Remote EAP 5 fails to start correctly
-------------------------------------
Key: JBIDE-14027
URL:
https://issues.jboss.org/browse/JBIDE-14027
Project: Tools (JBoss Tools)
Issue Type: Bug
Components: server
Affects Versions: 4.1.0.Alpha2
Environment: JBDS 7.0.0.Alpha2 B135
EAP 5.1.2/5.2.0
Reporter: Martin Malina
Assignee: Rob Stryker
Fix For: 4.1.0.Beta1, 4.2.0.Beta3
Attachments: JBIDE-14027.jmxfail.txt
When I set up a remote EAP 5 server and start it, it first starts normally:
{code}
2013-04-15 15:09:51,863 INFO [org.jboss.web.WebService] (main) Using RMI server
codebase:
http://MARVIN.BRQ.REDHAT.COM:8083/
2013-04-15 15:09:56,801 INFO [org.jboss.wsf.stack.jbws.NativeServerConfig] (main) JBoss
Web Services - Stack Native Core
2013-04-15 15:09:56,802 INFO [org.jboss.wsf.stack.jbws.NativeServerConfig] (main)
3.1.2.SP11
2013-04-15 15:09:58,404 INFO [org.jboss.logbridge.LogNotificationListener] (main) Adding
notification listener for logging mbean
"jboss.system:service=Logging,type=Log4jService" to server
org.jboss.mx.server.MBeanServerImpl@5aacc14[ defaultDomain='jboss' ]
2013-04-15 15:10:05,967 INFO [org.jboss.mail.MailService] (main) Mail Service bound to
java:/Mail
2013-04-15 15:10:07,097 WARN [org.jboss.jms.server.jbosssx.JBossASSecurityMetadataStore]
(main) WARNING! POTENTIAL SECURITY RISK. It has been detected that the MessageSucker
component which sucks messages from one node to another has not had its password changed
from the installation default. Please see the JBoss Messaging user guide for instructions
on how to do this.
2013-04-15 15:10:08,507 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService]
(main) JBossTS Transaction Service (JTA version - tag:JBOSSTS_4_6_1_GA_CP12) - JBoss Inc.
2013-04-15 15:10:08,508 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService]
(main) Setting up property manager MBean and JMX layer
2013-04-15 15:10:08,850 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService]
(main) Initializing recovery manager
2013-04-15 15:10:09,163 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService]
(main) Recovery manager configured
2013-04-15 15:10:09,163 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService]
(main) Binding TransactionManager JNDI Reference
2013-04-15 15:10:09,194 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService]
(main) Starting transaction recovery manager
2013-04-15 15:10:09,627 INFO [org.apache.catalina.core.AprLifecycleListener] (main) The
Apache Tomcat Native library which allows optimal performance in production environments
was not found on the java.library.path:
/home/rasp/jbossqa/runtimes/jboss-eap-5.1.2/jboss-as/bin/native
2013-04-15 15:10:09,698 INFO [org.apache.coyote.http11.Http11Protocol] (main)
Initializing Coyote HTTP/1.1 on http-marvin.brq.redhat.com%2F10.34.26.31-8080
2013-04-15 15:10:09,700 INFO [org.apache.coyote.ajp.AjpProtocol] (main) Initializing
Coyote AJP/1.3 on ajp-marvin.brq.redhat.com%2F10.34.26.31-8009
2013-04-15 15:10:09,742 INFO [org.apache.catalina.core.StandardService] (main) Starting
service jboss.web
2013-04-15 15:10:09,752 INFO [org.apache.catalina.core.StandardEngine] (main) Starting
Servlet Engine: JBoss Web/2.1.12.GA-patch-01
2013-04-15 15:10:09,859 INFO [org.apache.catalina.startup.Catalina] (main) Server
startup in 158 ms
2013-04-15 15:10:09,900 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment]
(main) deploy, ctxPath=/jbossws
2013-04-15 15:10:10,511 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment]
(main) deploy, ctxPath=/invoker
2013-04-15 15:10:10,555 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment]
(main) deploy, ctxPath=/web-console
2013-04-15 15:10:10,987 INFO [org.jboss.resource.deployers.RARDeployment] (main)
Required license terms exist, view
vfsfile:/home/rasp/jbossqa/runtimes/jboss-eap-5.1.2/jboss-as/server/default/deploy/jboss-local-jdbc.rar/META-INF/ra.xml
2013-04-15 15:10:11,002 INFO [org.jboss.resource.deployers.RARDeployment] (main)
Required license terms exist, view
vfsfile:/home/rasp/jbossqa/runtimes/jboss-eap-5.1.2/jboss-as/server/default/deploy/jboss-xa-jdbc.rar/META-INF/ra.xml
2013-04-15 15:10:11,011 INFO [org.jboss.resource.deployers.RARDeployment] (main)
Required license terms exist, view
vfsfile:/home/rasp/jbossqa/runtimes/jboss-eap-5.1.2/jboss-as/server/default/deploy/jms-ra.rar/META-INF/ra.xml
2013-04-15 15:10:11,024 INFO [org.jboss.resource.deployers.RARDeployment] (main)
Required license terms exist, view
vfsfile:/home/rasp/jbossqa/runtimes/jboss-eap-5.1.2/jboss-as/server/default/deploy/mail-ra.rar/META-INF/ra.xml
2013-04-15 15:10:11,045 INFO [org.jboss.resource.deployers.RARDeployment] (main)
Required license terms exist, view
vfsfile:/home/rasp/jbossqa/runtimes/jboss-eap-5.1.2/jboss-as/server/default/deploy/quartz-ra.rar/META-INF/ra.xml
2013-04-15 15:10:11,130 INFO [org.quartz.simpl.SimpleThreadPool] (main) Job execution
threads will use class loader of thread: main
2013-04-15 15:10:11,155 INFO [org.quartz.core.QuartzScheduler] (main) Quartz Scheduler
v.1.5.2 created.
2013-04-15 15:10:11,158 INFO [org.quartz.simpl.RAMJobStore] (main) RAMJobStore
initialized.
2013-04-15 15:10:11,159 INFO [org.quartz.impl.StdSchedulerFactory] (main) Quartz
scheduler 'DefaultQuartzScheduler' initialized from default resource file in
Quartz package: 'quartz.properties'
2013-04-15 15:10:11,159 INFO [org.quartz.impl.StdSchedulerFactory] (main) Quartz
scheduler version: 1.5.2
2013-04-15 15:10:11,161 INFO [org.quartz.core.QuartzScheduler] (main) Scheduler
DefaultQuartzScheduler_$_NON_CLUSTERED started.
2013-04-15 15:10:11,738 INFO
[org.jboss.resource.connectionmanager.ConnectionFactoryBindingService] (main) Bound
ConnectionManager 'jboss.jca:service=DataSourceBinding,name=DefaultDS' to JNDI
name 'java:DefaultDS'
2013-04-15 15:10:12,161 INFO [org.jboss.jms.server.ServerPeer] (main) JBoss Messaging
1.4.8.SP5 server [0] started
2013-04-15 15:10:12,283 INFO [org.jboss.jms.server.destination.QueueService] (main)
Queue[/queue/ExpiryQueue] started, fullSize=200000, pageSize=2000, downCacheSize=2000
2013-04-15 15:10:12,348 INFO [org.jboss.jms.server.connectionfactory.ConnectionFactory]
(main) Connector bisocket://MARVIN.BRQ.REDHAT.COM:4457 has leasing enabled, lease period
10000 milliseconds
2013-04-15 15:10:12,348 INFO [org.jboss.jms.server.connectionfactory.ConnectionFactory]
(main) org.jboss.jms.server.connectionfactory.ConnectionFactory@a4dbd0f started
2013-04-15 15:10:12,351 INFO [org.jboss.jms.server.destination.QueueService] (main)
Queue[/queue/DLQ] started, fullSize=200000, pageSize=2000, downCacheSize=2000
2013-04-15 15:10:12,352 INFO
[org.jboss.jms.server.connectionfactory.ConnectionFactoryJNDIMapper] (main)
supportsFailover attribute is true on connection factory:
jboss.messaging.connectionfactory:service=ClusteredConnectionFactory but post office is
non clustered. So connection factory will *not* support failover
2013-04-15 15:10:12,352 INFO
[org.jboss.jms.server.connectionfactory.ConnectionFactoryJNDIMapper] (main)
supportsLoadBalancing attribute is true on connection factory:
jboss.messaging.connectionfactory:service=ClusteredConnectionFactory but post office is
non clustered. So connection factory will *not* support load balancing
2013-04-15 15:10:12,354 INFO [org.jboss.jms.server.connectionfactory.ConnectionFactory]
(main) Connector bisocket://MARVIN.BRQ.REDHAT.COM:4457 has leasing enabled, lease period
10000 milliseconds
2013-04-15 15:10:12,354 INFO [org.jboss.jms.server.connectionfactory.ConnectionFactory]
(main) org.jboss.jms.server.connectionfactory.ConnectionFactory@606fdb4b started
2013-04-15 15:10:12,355 INFO [org.jboss.jms.server.connectionfactory.ConnectionFactory]
(main) Connector bisocket://MARVIN.BRQ.REDHAT.COM:4457 has leasing enabled, lease period
10000 milliseconds
2013-04-15 15:10:12,355 INFO [org.jboss.jms.server.connectionfactory.ConnectionFactory]
(main) org.jboss.jms.server.connectionfactory.ConnectionFactory@74bdaaa started
2013-04-15 15:10:12,473 INFO
[org.jboss.resource.connectionmanager.ConnectionFactoryBindingService] (main) Bound
ConnectionManager 'jboss.jca:service=ConnectionFactoryBinding,name=JmsXA' to JNDI
name 'java:JmsXA'
2013-04-15 15:10:12,670 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment]
(main) deploy, ctxPath=/admin-console
2013-04-15 15:10:13,118 INFO [javax.enterprise.resource.webcontainer.jsf.config] (main)
Initializing Mojarra (1.2_13-b01-FCS) for context '/admin-console'
2013-04-15 15:10:16,703 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment]
(main) deploy, ctxPath=/
2013-04-15 15:10:16,757 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment]
(main) deploy, ctxPath=/jmx-console
2013-04-15 15:10:16,862 INFO
[org.jboss.system.server.profileservice.ProfileServiceBootstrap] (main) Loading profile:
ProfileKey@684640e6[domain=default, server=default, name=default]
2013-04-15 15:10:16,900 INFO [org.apache.coyote.http11.Http11Protocol] (main) Starting
Coyote HTTP/1.1 on http-marvin.brq.redhat.com%2F10.34.26.31-8080
2013-04-15 15:10:16,984 INFO [org.apache.coyote.ajp.AjpProtocol] (main) Starting Coyote
AJP/1.3 on ajp-marvin.brq.redhat.com%2F10.34.26.31-8009
2013-04-15 15:10:17,010 INFO [org.jboss.bootstrap.microcontainer.ServerImpl] (main)
JBoss (Microcontainer) [5.1.2 (build: SVNTag=JBPAPP_5_1_2 date=201111102209)] Started in
34s:709ms
{code}
But a few seconds after that strange things happen:
{code}
2013-04-15 15:10:22,087 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment]
(HDScanner) undeploy, ctxPath=/jmx-console
2013-04-15 15:10:22,118 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment]
(HDScanner) undeploy, ctxPath=/
2013-04-15 15:10:22,143 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment]
(HDScanner) undeploy, ctxPath=/admin-console
2013-04-15 15:10:22,245 INFO
[org.jboss.resource.connectionmanager.ConnectionFactoryBindingService] (HDScanner) Unbound
ConnectionManager 'jboss.jca:service=ConnectionFactoryBinding,name=JmsXA' from
JNDI name 'java:JmsXA'
2013-04-15 15:10:22,261 INFO [org.jboss.jms.server.destination.QueueService] (HDScanner)
Queue[/queue/ExpiryQueue] stopped
2013-04-15 15:10:22,262 INFO [org.jboss.jms.server.connectionfactory.ConnectionFactory]
(HDScanner) org.jboss.jms.server.connectionfactory.ConnectionFactory@a4dbd0f undeployed
2013-04-15 15:10:22,263 INFO [org.jboss.jms.server.destination.QueueService] (HDScanner)
Queue[/queue/DLQ] stopped
2013-04-15 15:10:22,263 INFO [org.jboss.jms.server.connectionfactory.ConnectionFactory]
(HDScanner) org.jboss.jms.server.connectionfactory.ConnectionFactory@74bdaaa undeployed
2013-04-15 15:10:22,264 INFO [org.jboss.jms.server.connectionfactory.ConnectionFactory]
(HDScanner) org.jboss.jms.server.connectionfactory.ConnectionFactory@606fdb4b undeployed
2013-04-15 15:10:22,294 INFO [org.jboss.jms.server.ServerPeer] (HDScanner) JMS
ServerPeer[0] stopped
2013-04-15 15:10:22,300 INFO
[org.jboss.resource.connectionmanager.ConnectionFactoryBindingService] (HDScanner) Unbound
ConnectionManager 'jboss.jca:service=DataSourceBinding,name=DefaultDS' from JNDI
name 'java:DefaultDS'
2013-04-15 15:10:22,694 INFO [org.jboss.jdbc.HypersonicDatabase] (HDScanner) Database
standalone closed clean
2013-04-15 15:10:22,704 INFO [org.quartz.core.QuartzScheduler] (HDScanner) Scheduler
DefaultQuartzScheduler_$_NON_CLUSTERED shutting down.
2013-04-15 15:10:22,704 INFO [org.quartz.core.QuartzScheduler] (HDScanner) Scheduler
DefaultQuartzScheduler_$_NON_CLUSTERED paused.
2013-04-15 15:10:22,706 INFO [org.quartz.simpl.SimpleThreadPool] (HDScanner) There are
still 35 worker threads active. See javadoc runInThread(Runnable) for a possible
explanation
2013-04-15 15:10:22,706 INFO [org.quartz.core.QuartzScheduler] (HDScanner) Scheduler
DefaultQuartzScheduler_$_NON_CLUSTERED shutdown complete.
2013-04-15 15:10:22,745 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment]
(HDScanner) undeploy, ctxPath=/jbossws
2013-04-15 15:10:22,751 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment]
(HDScanner) undeploy, ctxPath=/invoker
2013-04-15 15:10:22,756 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment]
(HDScanner) undeploy, ctxPath=/web-console
2013-04-15 15:10:22,766 INFO [org.apache.coyote.http11.Http11Protocol] (HDScanner)
Pausing Coyote HTTP/1.1 on http-marvin.brq.redhat.com%2F10.34.26.31-8080
2013-04-15 15:10:22,767 INFO [org.apache.coyote.http11.Http11Protocol] (HDScanner)
Stopping Coyote HTTP/1.1 on http-marvin.brq.redhat.com%2F10.34.26.31-8080
2013-04-15 15:10:22,769 INFO [org.apache.coyote.ajp.AjpProtocol] (HDScanner) Pausing
Coyote AJP/1.3 on ajp-marvin.brq.redhat.com%2F10.34.26.31-8009
2013-04-15 15:10:22,770 INFO [org.apache.coyote.ajp.AjpProtocol] (HDScanner) Stopping
Coyote AJP/1.3 on ajp-marvin.brq.redhat.com%2F10.34.26.31-8009
2013-04-15 15:10:22,777 INFO [org.apache.catalina.core.StandardService] (HDScanner)
Stopping service jboss.web
2013-04-15 15:10:22,809 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService]
(HDScanner) Stopping transaction recovery manager
2013-04-15 15:10:22,812 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService]
(HDScanner) Destroying TransactionManagerService
2013-04-15 15:10:24,588 INFO [org.jboss.mail.MailService] (HDScanner) Mail service
'java:/Mail' removed from JNDI
2013-04-15 15:10:28,975 INFO [org.jboss.mail.MailService] (HDScanner) Mail Service bound
to java:/Mail
2013-04-15 15:10:29,495 WARN [org.jboss.jms.server.jbosssx.JBossASSecurityMetadataStore]
(HDScanner) WARNING! POTENTIAL SECURITY RISK. It has been detected that the MessageSucker
component which sucks messages from one node to another has not had its password changed
from the installation default. Please see the JBoss Messaging user guide for instructions
on how to do this.
2013-04-15 15:10:30,814 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService]
(HDScanner) JBossTS Transaction Service (JTA version - tag:JBOSSTS_4_6_1_GA_CP12) - JBoss
Inc.
2013-04-15 15:10:30,821 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService]
(HDScanner) Setting up property manager MBean and JMX layer
2013-04-15 15:10:30,844 ERROR
[org.jboss.kernel.plugins.dependency.AbstractKernelController] (HDScanner) Error
installing to Create: name=TransactionManager state=Configured
com.arjuna.common.util.exceptions.ManagementPluginException:
com.arjuna.common.util.exceptions.ManagementPluginException: Failed to register mbeans:
javax.management.InstanceAlreadyExistsException: com.arjuna.ats.properties:module=arjuna
already registered.
at
com.arjuna.common.internal.util.propertyservice.PropertyManagerImpl.addManagementPlugin(PropertyManagerImpl.java:496)
at
com.arjuna.ats.jbossatx.jta.TransactionManagerService.create(TransactionManagerService.java:193)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:616)
at
org.jboss.reflect.plugins.introspection.ReflectionUtils.invoke(ReflectionUtils.java:59)
at
org.jboss.reflect.plugins.introspection.ReflectMethodInfoImpl.invoke(ReflectMethodInfoImpl.java:150)
at
org.jboss.joinpoint.plugins.BasicMethodJoinPoint.dispatch(BasicMethodJoinPoint.java:66)
at
org.jboss.kernel.plugins.dependency.KernelControllerContextAction$JoinpointDispatchWrapper.execute(KernelControllerContextAction.java:243)
at
org.jboss.kernel.plugins.dependency.ExecutionWrapper.execute(ExecutionWrapper.java:47)
at
org.jboss.kernel.plugins.dependency.KernelControllerContextAction.dispatchExecutionWrapper(KernelControllerContextAction.java:111)
at
org.jboss.kernel.plugins.dependency.KernelControllerContextAction.dispatchJoinPoint(KernelControllerContextAction.java:72)
at
org.jboss.kernel.plugins.dependency.LifecycleAction.installActionInternal(LifecycleAction.java:221)
at
org.jboss.kernel.plugins.dependency.InstallsAwareAction.installAction(InstallsAwareAction.java:54)
at
org.jboss.kernel.plugins.dependency.InstallsAwareAction.installAction(InstallsAwareAction.java:42)
at
org.jboss.dependency.plugins.action.SimpleControllerContextAction.simpleInstallAction(SimpleControllerContextAction.java:62)
at
org.jboss.dependency.plugins.action.AccessControllerContextAction.install(AccessControllerContextAction.java:71)
at
org.jboss.dependency.plugins.AbstractControllerContextActions.install(AbstractControllerContextActions.java:51)
at
org.jboss.dependency.plugins.AbstractControllerContext.install(AbstractControllerContext.java:348)
at
org.jboss.dependency.plugins.AbstractController.install(AbstractController.java:1652)
at
org.jboss.dependency.plugins.AbstractController.incrementState(AbstractController.java:938)
at
org.jboss.dependency.plugins.AbstractController.resolveContexts(AbstractController.java:1082)
at
org.jboss.dependency.plugins.AbstractController.resolveContexts(AbstractController.java:988)
at org.jboss.dependency.plugins.AbstractController.install(AbstractController.java:778)
at org.jboss.dependency.plugins.AbstractController.install(AbstractController.java:543)
at
org.jboss.deployers.vfs.deployer.kernel.BeanMetaDataDeployer.deploy(BeanMetaDataDeployer.java:125)
at
org.jboss.deployers.vfs.deployer.kernel.BeanMetaDataDeployer.deploy(BeanMetaDataDeployer.java:52)
at
org.jboss.deployers.spi.deployer.helpers.AbstractSimpleRealDeployer.internalDeploy(AbstractSimpleRealDeployer.java:62)
at
org.jboss.deployers.spi.deployer.helpers.AbstractRealDeployer.deploy(AbstractRealDeployer.java:55)
at
org.jboss.deployers.plugins.deployers.DeployerWrapper.deploy(DeployerWrapper.java:179)
at
org.jboss.deployers.plugins.deployers.DeployersImpl.doDeploy(DeployersImpl.java:1454)
at
org.jboss.deployers.plugins.deployers.DeployersImpl.doInstallParentFirst(DeployersImpl.java:1172)
at
org.jboss.deployers.plugins.deployers.DeployersImpl.doInstallParentFirst(DeployersImpl.java:1193)
at org.jboss.deployers.plugins.deployers.DeployersImpl.install(DeployersImpl.java:1113)
at
org.jboss.dependency.plugins.AbstractControllerContext.install(AbstractControllerContext.java:348)
at
org.jboss.dependency.plugins.AbstractController.install(AbstractController.java:1652)
at
org.jboss.dependency.plugins.AbstractController.incrementState(AbstractController.java:938)
at
org.jboss.dependency.plugins.AbstractController.resolveContexts(AbstractController.java:1082)
at
org.jboss.dependency.plugins.AbstractController.resolveContexts(AbstractController.java:988)
at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:826)
at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:556)
at org.jboss.deployers.plugins.deployers.DeployersImpl.process(DeployersImpl.java:789)
at org.jboss.deployers.plugins.main.MainDeployerImpl.process(MainDeployerImpl.java:699)
at
org.jboss.system.server.profileservice.repository.MainDeployerAdapter.process(MainDeployerAdapter.java:117)
at org.jboss.system.server.profileservice.hotdeploy.HDScanner.scan(HDScanner.java:409)
at org.jboss.system.server.profileservice.hotdeploy.HDScanner.run(HDScanner.java:294)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:351)
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:178)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:165)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:267)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:679)
{code}