Jason Greene created AS7-5210:
---------------------------------
Summary: SingletonTestCase intermittently fails
Key: AS7-5210
URL:
https://issues.jboss.org/browse/AS7-5210
Project: Application Server 7
Issue Type: Bug
Components: Clustering
Reporter: Jason Greene
Assignee: Paul Ferraro
Fix For: 7.1.3.Final (EAP), 7.2.0.CR1
Error Message
Cannot deploy: singleton.war
Stacktrace
org.jboss.arquillian.container.spi.client.container.DeploymentException: Cannot deploy:
singleton.war
at
org.jboss.as.arquillian.container.ArchiveDeployer.deployInternal(ArchiveDeployer.java:83)
at
org.jboss.as.arquillian.container.ArchiveDeployer.deployInternal(ArchiveDeployer.java:64)
at org.jboss.as.arquillian.container.ArchiveDeployer.deploy(ArchiveDeployer.java:46)
at
org.jboss.as.arquillian.container.CommonDeployableContainer.deploy(CommonDeployableContainer.java:145)
at
org.jboss.arquillian.container.impl.client.container.ContainerDeployController$3.call(ContainerDeployController.java:161)
at
org.jboss.arquillian.container.impl.client.container.ContainerDeployController$3.call(ContainerDeployController.java:128)
at
org.jboss.arquillian.container.impl.client.container.ContainerDeployController.executeOperation(ContainerDeployController.java:271)
at
org.jboss.arquillian.container.impl.client.container.ContainerDeployController.deploy(ContainerDeployController.java:127)
at sun.reflect.GeneratedMethodAccessor19.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90)
at
org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:99)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:81)
at
org.jboss.arquillian.container.impl.client.ContainerDeploymentContextHandler.createDeploymentContext(ContainerDeploymentContextHandler.java:78)
at sun.reflect.GeneratedMethodAccessor5.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at
org.jboss.arquillian.container.impl.client.ContainerDeploymentContextHandler.createContainerContext(ContainerDeploymentContextHandler.java:57)
at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at
org.jboss.arquillian.container.impl.client.container.DeploymentExceptionHandler.verifyExpectedExceptionDuringDeploy(DeploymentExceptionHandler.java:50)
at sun.reflect.GeneratedMethodAccessor18.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:135)
at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:115)
at org.jboss.arquillian.core.impl.EventImpl.fire(EventImpl.java:67)
at
org.jboss.arquillian.container.test.impl.client.deployment.ClientDeployer.deploy(ClientDeployer.java:93)
at
org.jboss.as.test.clustering.cluster.singleton.SingletonTestCase.testSingletonService(SingletonTestCase.java:125)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42)
at org.jboss.arquillian.junit.Arquillian$6$1.invoke(Arquillian.java:270)
at
org.jboss.arquillian.container.test.impl.execution.LocalTestExecuter.execute(LocalTestExecuter.java:60)
at sun.reflect.GeneratedMethodAccessor12.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90)
at
org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:99)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:81)
at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:135)
at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:115)
at org.jboss.arquillian.core.impl.EventImpl.fire(EventImpl.java:67)
at
org.jboss.arquillian.container.test.impl.execution.ClientTestExecuter.execute(ClientTestExecuter.java:53)
at sun.reflect.GeneratedMethodAccessor11.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90)
at
org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:99)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:81)
at
org.jboss.arquillian.container.test.impl.client.ContainerEventController.createContext(ContainerEventController.java:142)
at
org.jboss.arquillian.container.test.impl.client.ContainerEventController.createTestContext(ContainerEventController.java:129)
at sun.reflect.GeneratedMethodAccessor10.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at
org.jboss.arquillian.test.impl.TestContextHandler.createTestContext(TestContextHandler.java:89)
at sun.reflect.GeneratedMethodAccessor3.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at
org.jboss.arquillian.test.impl.TestContextHandler.createClassContext(TestContextHandler.java:75)
at sun.reflect.GeneratedMethodAccessor2.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at
org.jboss.arquillian.test.impl.TestContextHandler.createSuiteContext(TestContextHandler.java:60)
at sun.reflect.GeneratedMethodAccessor1.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:135)
at
org.jboss.arquillian.test.impl.EventTestRunnerAdaptor.test(EventTestRunnerAdaptor.java:111)
at org.jboss.arquillian.junit.Arquillian$6.evaluate(Arquillian.java:263)
at org.jboss.arquillian.junit.Arquillian$4.evaluate(Arquillian.java:226)
at org.jboss.arquillian.junit.Arquillian.multiExecute(Arquillian.java:314)
at org.jboss.arquillian.junit.Arquillian.access$100(Arquillian.java:46)
at org.jboss.arquillian.junit.Arquillian$5.evaluate(Arquillian.java:240)
at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:47)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222)
at org.jboss.arquillian.junit.Arquillian$2.evaluate(Arquillian.java:185)
at org.jboss.arquillian.junit.Arquillian.multiExecute(Arquillian.java:314)
at org.jboss.arquillian.junit.Arquillian.access$100(Arquillian.java:46)
at org.jboss.arquillian.junit.Arquillian$3.evaluate(Arquillian.java:199)
at org.junit.runners.ParentRunner.run(ParentRunner.java:300)
at org.jboss.arquillian.junit.Arquillian.run(Arquillian.java:147)
at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:234)
at
org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:133)
at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:114)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at
org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:188)
at
org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:166)
at
org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:86)
at
org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:101)
at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:74)
Standard Output
20:10:14,832 INFO [org.jboss.web] (MSC service thread 1-5) JBAS018224: Unregister web
context: /singleton
20:10:14,892 INFO [stdout] (MSC service thread 1-5) Initiating removal of
jboss.test.myservice
20:10:14,904 INFO [org.jboss.as.clustering.singleton] (notification-thread-2) JBAS010342:
node-0/cluster elected as the singleton provider of the jboss.test.myservice service
20:10:14,904 INFO [org.jboss.as.clustering.singleton] (notification-thread-2) JBAS010340:
This node will now operate as the singleton provider of the jboss.test.myservice service
20:10:14,919 INFO [stdout] (MSC service thread 1-5) Removal of jboss.test.myservice
complete
20:10:14,928 INFO [org.jboss.as.clustering.infinispan] (MSC service thread 1-2)
JBAS010282: Stopped default cache from cluster container
20:10:15,045 INFO [org.jboss.as.server.deployment] (MSC service thread 1-5) JBAS015877:
Stopped deployment singleton.war in 244ms
20:10:15,258 INFO [org.jboss.as.clustering] (ViewHandler,cluster,node-0/cluster)
JBAS010225: New cluster view for partition cluster (id: 2, delta: -1, merge: false) :
[node-0/cluster]
20:10:15,264 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport]
(ViewHandler,cluster,node-0/cluster) ISPN000094: Received new cluster view:
[node-0/cluster|2] [node-0/cluster]
20:10:15,323 INFO [org.jboss.as.clustering] (ViewHandler,cluster,node-0/cluster)
JBAS010225: New cluster view for partition cluster (id: 3, delta: 1, merge: false) :
[node-0/cluster, node-1/cluster]
20:10:15,324 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport]
(ViewHandler,cluster,node-0/cluster) ISPN000094: Received new cluster view:
[node-0/cluster|3] [node-0/cluster, node-1/cluster]
20:10:15,429 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC
service thread 1-5) ISPN000082: Stopping the RpcDispatcher
20:10:15,484 INFO [org.jboss.as.repository] (management-handler-thread - 3) JBAS014901:
Content removed from location
/home/jenkins/jenkins-work/workspace/as7-master-testsuite/testsuite/integration/clust/target/jbossas-clustering-udp-1/standalone/data/content/14/b2af1a7499fd854d8f00a34029906d453371a8/content
20:10:15,487 INFO [org.jboss.as.server] (management-handler-thread - 3) JBAS018558:
Undeployed "singleton.war"
20:10:15,491 INFO
[org.jboss.arquillian.container.test.impl.client.container.ClientContainerController]
(main) Manual stopping of a server instance
URLs are:
http://127.0.0.1:8080/singleton/service,
http://127.0.0.1:8180/singleton/service
20:10:16,161 INFO
[org.jboss.arquillian.container.test.impl.client.container.ClientContainerController]
(main) Manual starting of a server instance
20:10:16,165 INFO [org.jboss.as.arquillian.container.managed.ManagedDeployableContainer]
(main) Starting container with: [/opt/jdk1.6.0_29/bin/java, -Xmx512m,
-XX:MaxPermSize=256m,
-Djboss.dist=/home/jenkins/jenkins-work/workspace/as7-master-testsuite/testsuite/integration/clust/../../../build/target/jboss-as-7.2.0.Alpha1-SNAPSHOT,
-Djava.net.preferIPv4Stack=true, -Djava.net.preferIPv6Addresses=false,
-Dts.timeout.factor=100, -Dnode0=127.0.0.1, -Dnode1=127.0.0.1, -Dmcast=230.0.0.4,
-Djbossas.ts.submodule.dir=/home/jenkins/jenkins-work/workspace/as7-master-testsuite/testsuite/integration/clust,
-Djbossas.ts.integ.dir=/home/jenkins/jenkins-work/workspace/as7-master-testsuite/testsuite/integration/clust/..,
-Djbossas.ts.dir=/home/jenkins/jenkins-work/workspace/as7-master-testsuite/testsuite/integration/clust/../..,
-Djbossas.project.dir=/home/jenkins/jenkins-work/workspace/as7-master-testsuite/testsuite/integration/clust/../../..,
-Djboss.dist=/home/jenkins/jenkins-work/workspace/as7-master-testsuite/testsuite/integration/clust/../../../build/target/jboss-as-7.2.0.Alpha1-SNAPSHOT,
-Djboss.inst=/home/jenkins/jenkins-work/workspace/as7-master-testsuite/testsuite/integration/clust/target/jbossas-clustering-udp-1,
-Djboss.node.name=node-1, -Djboss.port.offset=100, -ea,
-Djboss.home.dir=/home/jenkins/jenkins-work/workspace/as7-master-testsuite/testsuite/integration/clust/target/jbossas-clustering-udp-1,
-Dorg.jboss.boot.log.file=/home/jenkins/jenkins-work/workspace/as7-master-testsuite/testsuite/integration/clust/target/jbossas-clustering-udp-1/standalone/log/boot.log,
-Dlogging.configuration=file:/home/jenkins/jenkins-work/workspace/as7-master-testsuite/testsuite/integration/clust/target/jbossas-clustering-udp-1/standalone/configuration/logging.properties,
-Djboss.bundles.dir=/home/jenkins/jenkins-work/workspace/as7-master-testsuite/testsuite/integration/clust/../../../build/target/jboss-as-7.2.0.Alpha1-SNAPSHOT/bundles,
-jar,
/home/jenkins/jenkins-work/workspace/as7-master-testsuite/testsuite/integration/clust/target/jbossas-clustering-udp-1/jboss-modules.jar,
-mp,
/home/jenkins/jenkins-work/workspace/as7-master-testsuite/testsuite/integration/clust/../../../build/target/jboss-as-7.2.0.Alpha1-SNAPSHOT/modules,
-jaxpmodule, javax.xml.jaxp-provider, org.jboss.as.standalone, -server-config,
standalone-ha.xml]
20:10:16,814 INFO [org.jboss.modules] JBoss Modules version 1.1.2.GA
20:10:17,067 INFO [org.jboss.msc] JBoss MSC version 1.0.2.GA
20:10:17,138 INFO [org.jboss.as] JBAS015899: JBoss AS 7.2.0.Alpha1-SNAPSHOT
"Steropes" starting
20:10:19,095 INFO [org.xnio] XNIO Version 3.0.5.GA
20:10:19,100 INFO [org.jboss.as.server] JBAS015888: Creating http management service
using socket-binding (management-http)
20:10:19,106 INFO [org.xnio.nio] XNIO NIO Implementation Version 3.0.5.GA
20:10:19,120 INFO [org.jboss.remoting] JBoss Remoting version 3.2.8.GA
20:10:19,158 INFO [org.jboss.as.logging] JBAS011502: Removing bootstrap log handlers
20:10:19,163 INFO [org.jboss.as.configadmin] (ServerService Thread Pool -- 29)
JBAS016200: Activating ConfigAdmin Subsystem
20:10:19,203 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 34)
JBAS010280: Activating Infinispan subsystem.
20:10:19,268 INFO [org.jboss.as.security] (ServerService Thread Pool -- 50) JBAS013171:
Activating Security Subsystem
20:10:19,282 INFO [org.jboss.as.naming] (ServerService Thread Pool -- 44) JBAS011800:
Activating Naming Subsystem
20:10:19,292 INFO [org.jboss.as.clustering.jgroups] (ServerService Thread Pool -- 38)
JBAS010260: Activating JGroups subsystem.
20:10:19,292 INFO [org.jboss.as.osgi] (ServerService Thread Pool -- 45) JBAS011906:
Activating OSGi Subsystem
20:10:19,345 INFO [org.jboss.as.security] (MSC service thread 1-1) JBAS013170: Current
PicketBox version=4.0.9.Final
20:10:19,378 INFO [org.jboss.as.webservices] (ServerService Thread Pool -- 54)
JBAS015537: Activating WebServices Extension
20:10:19,424 INFO [org.jboss.as.connector.logging] (MSC service thread 1-3) JBAS010408:
Starting JCA Subsystem (JBoss IronJacamar 1.0.11.Final)
20:10:19,532 INFO [org.jboss.as.naming] (MSC service thread 1-5) JBAS011802: Starting
Naming Service
20:10:19,576 INFO [org.jboss.as.mail.extension] (MSC service thread 1-4) JBAS015400:
Bound mail session [java:jboss/mail/Default]
20:10:19,767 INFO [org.jboss.as.connector.subsystems.datasources] (ServerService Thread
Pool -- 30) JBAS010403: Deploying JDBC-compliant driver class org.h2.Driver (version 1.3)
20:10:20,138 INFO [org.apache.coyote.http11.Http11Protocol] (MSC service thread 1-1)
Starting Coyote HTTP/1.1 on http-/127.0.0.1:8180
20:10:20,139 INFO [org.apache.coyote.ajp.AjpProtocol] (MSC service thread 1-6) Starting
Coyote AJP/1.3 on ajp-/127.0.0.1:8109
20:10:20,165 INFO [org.jboss.ws.common.management.AbstractServerConfig] (MSC service
thread 1-3) JBoss Web Services - Stack CXF Server 4.1.0.Beta1
20:10:20,294 INFO [org.jboss.modcluster.ModClusterService] (ServerService Thread Pool --
56) Initializing mod_cluster 1.2.1.Final
20:10:20,326 WARN [org.jgroups.protocols.pbcast.GMS] (ViewHandler,cluster,node-0/cluster)
node-0/cluster: failed to collect all ACKs (expected=1) for unicast view
[node-0/cluster|3] [node-0/cluster, node-1/cluster] after 5000ms, missing ACKs from
[node-1/cluster]
20:10:20,363 INFO [org.jboss.modcluster.advertise.impl.AdvertiseListenerImpl]
(ServerService Thread Pool -- 56) Listening to proxy advertisements on 224.0.1.105:23,364
20:10:20,580 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder]
(ServerService Thread Pool -- 34) ISPN000152: Passivation configured without an eviction
policy being selected. Only manually evicted entities will be passivated.
20:10:20,601 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder]
(ServerService Thread Pool -- 34) ISPN000152: Passivation configured without an eviction
policy being selected. Only manually evicted entities will be passivated.
20:10:20,802 INFO [org.jboss.as.server.deployment.scanner] (MSC service thread 1-4)
JBAS015012: Started FileSystemDeploymentService for directory
/home/jenkins/jenkins-work/workspace/as7-master-testsuite/testsuite/integration/clust/target/jbossas-clustering-udp-1/standalone/deployments
20:10:20,826 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread
1-3) JBAS010400: Bound data source [java:jboss/datasources/ExampleDS]
20:10:20,828 INFO [org.jboss.as.remoting] (MSC service thread 1-2) JBAS017100: Listening
on 127.0.0.1:10099
20:10:20,835 INFO [org.jboss.as.remoting] (MSC service thread 1-6) JBAS017100: Listening
on 127.0.0.1:4547
20:10:20,959 INFO [org.jboss.as] (Controller Boot Thread) JBAS015961: Http management
interface listening on
http://127.0.0.1:10090/management
20:10:20,960 INFO [org.jboss.as] (Controller Boot Thread) JBAS015951: Admin console
listening on
http://127.0.0.1:10090
20:10:20,960 INFO [org.jboss.as] (Controller Boot Thread) JBAS015874: JBoss AS
7.2.0.Alpha1-SNAPSHOT "Steropes" started in 4494ms - Started 138 of 285 services
(146 services are passive or on-demand)
20:10:22,809 INFO [org.jboss.as.repository] (management-handler-thread - 2) JBAS014900:
Content added at location
/home/jenkins/jenkins-work/workspace/as7-master-testsuite/testsuite/integration/clust/target/jbossas-clustering-udp-1/standalone/data/content/5f/1d92b95d09a943ea73a104108edc7f7c5405f4/content
20:10:22,867 INFO [org.jboss.as.server.deployment] (MSC service thread 1-1) JBAS015876:
Starting deployment of "singleton.war"
20:10:23,241 INFO [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011006: OSGi
Framework - 2.0.0.CR5
20:10:23,546 WARN [org.jboss.as.dependency.private] (MSC service thread 1-2) JBAS018567:
Deployment "deployment.singleton.war" is using a private module
("org.jboss.as.clustering.common:main") which may be changed or removed in
future versions without notice.
20:10:23,550 WARN [org.jboss.as.dependency.private] (MSC service thread 1-2) JBAS018567:
Deployment "deployment.singleton.war" is using a private module
("org.jboss.as.clustering.singleton:main") which may be changed or removed in
future versions without notice.
20:10:23,552 WARN [org.jboss.as.dependency.private] (MSC service thread 1-2) JBAS018567:
Deployment "deployment.singleton.war" is using a private module
("org.jboss.as.server:main") which may be changed or removed in future versions
without notice.
20:10:24,090 INFO [org.jboss.web] (MSC service thread 1-4) JBAS018210: Register web
context: /singleton
20:10:24,835 WARN [org.jgroups.protocols.UDP] (ServerService Thread Pool -- 28) send
buffer of socket java.net.DatagramSocket@4ca49360 was set to 640KB, but the OS only
allocated 131.07KB. This might lead to performance problems. Please set your max send
buffer in the OS correctly (e.g. net.core.wmem_max on Linux)
20:10:24,837 WARN [org.jgroups.protocols.UDP] (ServerService Thread Pool -- 28) receive
buffer of socket java.net.DatagramSocket@4ca49360 was set to 20MB, but the OS only
allocated 131.07KB. This might lead to performance problems. Please set your max receive
buffer in the OS correctly (e.g. net.core.rmem_max on Linux)
20:10:24,837 WARN [org.jgroups.protocols.UDP] (ServerService Thread Pool -- 28) send
buffer of socket java.net.MulticastSocket@6f21a9ec was set to 640KB, but the OS only
allocated 131.07KB. This might lead to performance problems. Please set your max send
buffer in the OS correctly (e.g. net.core.wmem_max on Linux)
20:10:24,838 WARN [org.jgroups.protocols.UDP] (ServerService Thread Pool -- 28) receive
buffer of socket java.net.MulticastSocket@6f21a9ec was set to 25MB, but the OS only
allocated 131.07KB. This might lead to performance problems. Please set your max receive
buffer in the OS correctly (e.g. net.core.rmem_max on Linux)
20:10:24,842 INFO [stdout] (ServerService Thread Pool -- 28)
20:10:24,842 INFO [stdout] (ServerService Thread Pool -- 28)
-------------------------------------------------------------------
20:10:24,843 INFO [stdout] (ServerService Thread Pool -- 28) GMS: address=node-1/cluster,
cluster=cluster, physical address=127.0.0.1:55300
20:10:24,843 INFO [stdout] (ServerService Thread Pool -- 28)
-------------------------------------------------------------------
20:10:24,959 INFO [org.jboss.as.clustering] (Incoming-7,null) JBAS010225: New cluster
view for partition cluster (id: 4, delta: 1, merge: false) : [node-0/cluster,
node-1/cluster, node-1/cluster]
20:10:24,960 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport]
(Incoming-7,null) ISPN000094: Received new cluster view: [node-0/cluster|4]
[node-0/cluster, node-1/cluster, node-1/cluster]
20:10:25,144 WARN [org.jgroups.protocols.TP$ProtocolAdapter] (OOB-6,null) dropping
unicast message to wrong destination 38a44abb-118c-673d-44b7-d28c28949616; my local_addr
is node-1/cluster
20:10:25,171 WARN [org.jgroups.protocols.TP$ProtocolAdapter] (OOB-7,null) dropping
unicast message to wrong destination 38a44abb-118c-673d-44b7-d28c28949616; my local_addr
is node-1/cluster
20:10:25,336 WARN [org.jgroups.protocols.TP$ProtocolAdapter] (OOB-8,null) dropping
unicast message to wrong destination 38a44abb-118c-673d-44b7-d28c28949616; my local_addr
is node-1/cluster
20:10:26,171 WARN [org.jgroups.protocols.TP$ProtocolAdapter] (OOB-14,null) dropping
unicast message to wrong destination 38a44abb-118c-673d-44b7-d28c28949616; my local_addr
is node-1/cluster
20:10:27,172 WARN [org.jgroups.protocols.TP$ProtocolAdapter] (OOB-15,null) dropping
unicast message to wrong destination 38a44abb-118c-673d-44b7-d28c28949616; my local_addr
is node-1/cluster
20:10:27,327 WARN [org.jgroups.protocols.TP$ProtocolAdapter] (OOB-16,null) dropping
unicast message to wrong destination 38a44abb-118c-673d-44b7-d28c28949616; my local_addr
is node-1/cluster
20:10:27,337 WARN [org.jgroups.protocols.TP$ProtocolAdapter] (OOB-17,null) dropping
unicast message to wrong destination 38a44abb-118c-673d-44b7-d28c28949616; my local_addr
is node-1/cluster
20:10:27,956 WARN [org.jgroups.protocols.pbcast.GMS] (ServerService Thread Pool -- 28)
JOIN(node-1/cluster) sent to node-0/cluster timed out (after 3000 ms), on try 1
20:10:28,173 WARN [org.jgroups.protocols.TP$ProtocolAdapter] (OOB-19,null) dropping
unicast message to wrong destination 38a44abb-118c-673d-44b7-d28c28949616; my local_addr
is node-1/cluster
20:10:29,175 WARN [org.jgroups.protocols.TP$ProtocolAdapter] (OOB-19,null) dropping
unicast message to wrong destination 38a44abb-118c-673d-44b7-d28c28949616; my local_addr
is node-1/cluster
20:10:29,339 WARN [org.jgroups.protocols.TP$ProtocolAdapter] (OOB-19,null) dropping
unicast message to wrong destination 38a44abb-118c-673d-44b7-d28c28949616; my local_addr
is node-1/cluster
20:10:29,960 WARN [org.jgroups.protocols.pbcast.GMS] (ViewHandler,cluster,node-0/cluster)
node-0/cluster: failed to collect all ACKs (expected=2) for view [node-0/cluster|4] after
5000ms, missing ACKs from [node-1/cluster]
20:10:30,043 WARN [org.jgroups.protocols.pbcast.GMS] (ViewHandler,cluster,node-0/cluster)
node-1/cluster already present; returning existing view [node-0/cluster|4]
[node-0/cluster, node-1/cluster, node-1/cluster]
20:10:30,143 WARN [org.jgroups.protocols.TP$ProtocolAdapter] (OOB-18,null) dropping
unicast message to wrong destination 38a44abb-118c-673d-44b7-d28c28949616; my local_addr
is node-1/cluster
20:10:30,175 WARN [org.jgroups.protocols.TP$ProtocolAdapter] (OOB-18,null) dropping
unicast message to wrong destination 38a44abb-118c-673d-44b7-d28c28949616; my local_addr
is node-1/cluster
20:10:30,184 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport]
(ServerService Thread Pool -- 28) ISPN000078: Starting JGroups Channel
20:10:30,203 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport]
(ServerService Thread Pool -- 28) ISPN000094: Received new cluster view:
[node-0/cluster|4] [node-0/cluster, 38a44abb-118c-673d-44b7-d28c28949616, node-1/cluster]
20:10:30,207 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport]
(ServerService Thread Pool -- 28) ISPN000079: Cache local address is node-1/cluster,
physical addresses are [127.0.0.1:55300]
20:10:30,226 INFO [org.infinispan.factories.GlobalComponentRegistry] (ServerService
Thread Pool -- 28) ISPN000128: Infinispan version: Infinispan 'Brahma'
5.1.5.FINAL
20:10:30,553 INFO [org.infinispan.jmx.CacheJmxRegistration] (ServerService Thread Pool --
28) ISPN000031: MBeans were successfully registered to the platform mbean server.
20:10:30,859 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 28)
JBAS010281: Started default cache from cluster container
20:10:30,896 ERROR [org.jboss.as.clustering] (MSC service thread 1-6) JBAS010223:
ViewAccepted failed: java.lang.IllegalStateException: JBAS010240: Address
38a44abb-118c-673d-44b7-d28c28949616 not registered in transport layer
at
org.jboss.as.clustering.impl.CoreGroupCommunicationService$ClusterNodeFactoryImpl.getClusterNode(CoreGroupCommunicationService.java:1350)
[jboss-as-clustering-impl-7.2.0.Alpha1-SNAPSHOT.jar:7.2.0.Alpha1-SNAPSHOT]
at
org.jboss.as.clustering.impl.CoreGroupCommunicationService.translateAddresses(CoreGroupCommunicationService.java:1010)
[jboss-as-clustering-impl-7.2.0.Alpha1-SNAPSHOT.jar:7.2.0.Alpha1-SNAPSHOT]
at
org.jboss.as.clustering.impl.CoreGroupCommunicationService$GroupView.<init>(CoreGroupCommunicationService.java:1101)
[jboss-as-clustering-impl-7.2.0.Alpha1-SNAPSHOT.jar:7.2.0.Alpha1-SNAPSHOT]
at
org.jboss.as.clustering.impl.CoreGroupCommunicationService.processViewChange(CoreGroupCommunicationService.java:945)
[jboss-as-clustering-impl-7.2.0.Alpha1-SNAPSHOT.jar:7.2.0.Alpha1-SNAPSHOT]
at
org.jboss.as.clustering.impl.CoreGroupCommunicationService$MembershipListenerImpl.viewAccepted(CoreGroupCommunicationService.java:1403)
[jboss-as-clustering-impl-7.2.0.Alpha1-SNAPSHOT.jar:7.2.0.Alpha1-SNAPSHOT]
at
org.jboss.as.clustering.impl.CoreGroupCommunicationService.start(CoreGroupCommunicationService.java:793)
[jboss-as-clustering-impl-7.2.0.Alpha1-SNAPSHOT.jar:7.2.0.Alpha1-SNAPSHOT]
at
org.jboss.as.clustering.impl.CoreGroupCommunicationService.start(CoreGroupCommunicationService.java:158)
[jboss-as-clustering-impl-7.2.0.Alpha1-SNAPSHOT.jar:7.2.0.Alpha1-SNAPSHOT]
at
org.jboss.msc.service.ServiceControllerImpl$StartTask.startService(ServiceControllerImpl.java:1811)
[jboss-msc-1.0.2.GA.jar:1.0.2.GA]
at
org.jboss.msc.service.ServiceControllerImpl$StartTask.run(ServiceControllerImpl.java:1746)
[jboss-msc-1.0.2.GA.jar:1.0.2.GA]
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
[rt.jar:1.6.0_29]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
[rt.jar:1.6.0_29]
at java.lang.Thread.run(Thread.java:662) [rt.jar:1.6.0_29]
20:10:31,010 INFO [org.jboss.as.clustering.singleton] (notification-thread-2) JBAS010342:
node-1/cluster elected as the singleton provider of the jboss.test.myservice service
20:10:31,011 INFO [org.jboss.as.clustering.singleton] (notification-thread-2) JBAS010341:
This node will no longer operate as the singleton provider of the jboss.test.myservice
service
20:10:31,029 ERROR [org.jboss.msc.service.fail] (ServerService Thread Pool -- 28)
MSC00001: Failed to start service jboss.test.myservice:
org.jboss.msc.service.StartException in service jboss.test.myservice:
java.lang.NullPointerException
at org.jboss.as.clustering.msc.AsynchronousService$1.run(AsynchronousService.java:87)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
[rt.jar:1.6.0_29]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
[rt.jar:1.6.0_29]
at java.lang.Thread.run(Thread.java:662) [rt.jar:1.6.0_29]
at org.jboss.threads.JBossThread.run(JBossThread.java:122)
[jboss-threads-2.0.0.GA.jar:2.0.0.GA]
Caused by: java.lang.NullPointerException
at org.jboss.as.clustering.singleton.SingletonService.elected(SingletonService.java:159)
at
org.jboss.as.clustering.singleton.SingletonService.serviceProvidersChanged(SingletonService.java:139)
at
org.jboss.as.clustering.service.ServiceProviderRegistryService.register(ServiceProviderRegistryService.java:87)
at org.jboss.as.clustering.singleton.SingletonService.start(SingletonService.java:112)
at org.jboss.as.clustering.msc.AsynchronousService$1.run(AsynchronousService.java:82)
... 4 more
20:10:31,041 ERROR
[org.apache.catalina.core.ContainerBase.[jboss.web].[default-host].[/singleton]] (MSC
service thread 1-4) Exception sending context initialized event to listener instance of
class org.jboss.as.test.clustering.cluster.singleton.service.MyServiceContextListener:
java.lang.IllegalStateException: org.jboss.msc.service.StartException in service
jboss.test.myservice: java.lang.NullPointerException
at
org.jboss.as.test.clustering.cluster.singleton.service.MyServiceContextListener.contextInitialized(MyServiceContextListener.java:59)
[classes:]
at
org.apache.catalina.core.StandardContext.contextListenerStart(StandardContext.java:3392)
[jbossweb-7.0.16.Final.jar:]
at org.apache.catalina.core.StandardContext.start(StandardContext.java:3850)
[jbossweb-7.0.16.Final.jar:]
at org.jboss.as.web.deployment.WebDeploymentService.start(WebDeploymentService.java:101)
[jboss-as-web-7.2.0.Alpha1-SNAPSHOT.jar:7.2.0.Alpha1-SNAPSHOT]
at
org.jboss.msc.service.ServiceControllerImpl$StartTask.startService(ServiceControllerImpl.java:1811)
[jboss-msc-1.0.2.GA.jar:1.0.2.GA]
at
org.jboss.msc.service.ServiceControllerImpl$StartTask.run(ServiceControllerImpl.java:1746)
[jboss-msc-1.0.2.GA.jar:1.0.2.GA]
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
[rt.jar:1.6.0_29]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
[rt.jar:1.6.0_29]
at java.lang.Thread.run(Thread.java:662) [rt.jar:1.6.0_29]
Caused by: org.jboss.msc.service.StartException in service jboss.test.myservice:
java.lang.NullPointerException
at org.jboss.as.clustering.msc.AsynchronousService$1.run(AsynchronousService.java:87)
[jboss-as-clustering-common-7.2.0.Alpha1-SNAPSHOT.jar:7.2.0.Alpha1-SNAPSHOT]
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
[rt.jar:1.6.0_29]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
[rt.jar:1.6.0_29]
at java.lang.Thread.run(Thread.java:662) [rt.jar:1.6.0_29]
at org.jboss.threads.JBossThread.run(JBossThread.java:122)
Caused by: java.lang.NullPointerException
at org.jboss.as.clustering.singleton.SingletonService.elected(SingletonService.java:159)
[jboss-as-clustering-singleton-7.2.0.Alpha1-SNAPSHOT.jar:7.2.0.Alpha1-SNAPSHOT]
at
org.jboss.as.clustering.singleton.SingletonService.serviceProvidersChanged(SingletonService.java:139)
[jboss-as-clustering-singleton-7.2.0.Alpha1-SNAPSHOT.jar:7.2.0.Alpha1-SNAPSHOT]
at
org.jboss.as.clustering.service.ServiceProviderRegistryService.register(ServiceProviderRegistryService.java:87)
at org.jboss.as.clustering.singleton.SingletonService.start(SingletonService.java:112)
[jboss-as-clustering-singleton-7.2.0.Alpha1-SNAPSHOT.jar:7.2.0.Alpha1-SNAPSHOT]
at org.jboss.as.clustering.msc.AsynchronousService$1.run(AsynchronousService.java:82)
[jboss-as-clustering-common-7.2.0.Alpha1-SNAPSHOT.jar:7.2.0.Alpha1-SNAPSHOT]
... 4 more
20:10:31,178 WARN [org.jgroups.protocols.TP$ProtocolAdapter] (OOB-20,null) dropping
unicast message to wrong destination 38a44abb-118c-673d-44b7-d28c28949616; my local_addr
is node-1/cluster
20:10:31,202 ERROR [org.apache.catalina.core.StandardContext] (MSC service thread 1-4)
Error listenerStart
20:10:31,204 ERROR [org.apache.catalina.core.StandardContext] (MSC service thread 1-4)
Context [/singleton] startup failed due to previous errors
20:10:31,254 INFO [stdout] (MSC service thread 1-4) Initiating removal of
jboss.test.myservice
20:10:31,261 INFO [stdout] (MSC service thread 1-4) Removal of jboss.test.myservice
complete
20:10:31,264 ERROR
[org.apache.catalina.core.ContainerBase.[jboss.web].[default-host].[/singleton]] (MSC
service thread 1-4) Exception sending context destroyed event to listener instance of
class org.jboss.as.test.clustering.cluster.singleton.service.MyServiceContextListener:
java.lang.IllegalStateException: jboss.test.myservice.service state = DOWN
at
org.jboss.as.test.clustering.cluster.singleton.service.MyServiceContextListener.verifyRemoved(MyServiceContextListener.java:75)
[classes:]
at
org.jboss.as.test.clustering.cluster.singleton.service.MyServiceContextListener.contextDestroyed(MyServiceContextListener.java:68)
[classes:]
at org.apache.catalina.core.StandardContext.listenerStop(StandardContext.java:3489)
[jbossweb-7.0.16.Final.jar:]
at org.apache.catalina.core.StandardContext.stop(StandardContext.java:3999)
[jbossweb-7.0.16.Final.jar:]
at org.apache.catalina.core.StandardContext.start(StandardContext.java:3917)
[jbossweb-7.0.16.Final.jar:]
at org.jboss.as.web.deployment.WebDeploymentService.start(WebDeploymentService.java:101)
[jboss-as-web-7.2.0.Alpha1-SNAPSHOT.jar:7.2.0.Alpha1-SNAPSHOT]
at
org.jboss.msc.service.ServiceControllerImpl$StartTask.startService(ServiceControllerImpl.java:1811)
[jboss-msc-1.0.2.GA.jar:1.0.2.GA]
at
org.jboss.msc.service.ServiceControllerImpl$StartTask.run(ServiceControllerImpl.java:1746)
[jboss-msc-1.0.2.GA.jar:1.0.2.GA]
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
[rt.jar:1.6.0_29]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
[rt.jar:1.6.0_29]
at java.lang.Thread.run(Thread.java:662) [rt.jar:1.6.0_29]
20:10:31,301 ERROR [org.jboss.msc.service.fail] (MSC service thread 1-4) MSC00001: Failed
to start service jboss.web.deployment.default-host./singleton:
org.jboss.msc.service.StartException in service
jboss.web.deployment.default-host./singleton: JBAS018040: Failed to start context
at org.jboss.as.web.deployment.WebDeploymentService.start(WebDeploymentService.java:106)
at
org.jboss.msc.service.ServiceControllerImpl$StartTask.startService(ServiceControllerImpl.java:1811)
[jboss-msc-1.0.2.GA.jar:1.0.2.GA]
at
org.jboss.msc.service.ServiceControllerImpl$StartTask.run(ServiceControllerImpl.java:1746)
[jboss-msc-1.0.2.GA.jar:1.0.2.GA]
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
[rt.jar:1.6.0_29]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
[rt.jar:1.6.0_29]
at java.lang.Thread.run(Thread.java:662) [rt.jar:1.6.0_29]
20:10:31,305 INFO [org.jboss.as.clustering.infinispan] (MSC service thread 1-1)
JBAS010282: Stopped default cache from cluster container
20:10:31,321 WARN [org.jgroups.protocols.pbcast.NAKACK2] (Incoming-13,null)
node-0/cluster: dropped message 4 from node-1/cluster (sender not in table
[node-0/cluster, node-1/cluster]), view=[node-0/cluster|5] [node-0/cluster,
node-1/cluster]
20:10:31,319 INFO [org.jboss.as.clustering] (Incoming-11,null) JBAS010225: New cluster
view for partition cluster (id: 5, delta: -1, merge: false) : [node-0/cluster,
node-1/cluster]
20:10:31,325 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport]
(Incoming-11,null) ISPN000094: Received new cluster view: [node-0/cluster|5]
[node-0/cluster, node-1/cluster]
20:10:31,806 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC
service thread 1-3) ISPN000082: Stopping the RpcDispatcher
20:10:31,822 INFO [org.jboss.as.server] (management-handler-thread - 2) JBAS015870:
Deploy of deployment "singleton.war" was rolled back with failure message
{"JBAS014671: Failed services" =>
{"jboss.web.deployment.default-host./singleton" =>
"org.jboss.msc.service.StartException in service
jboss.web.deployment.default-host./singleton: JBAS018040: Failed to start context"}}
20:10:31,908 INFO [org.jboss.as.server.deployment] (MSC service thread 1-5) JBAS015877:
Stopped deployment singleton.war in 86ms
20:10:31,958 INFO [org.jboss.web] (MSC service thread 1-3) JBAS018224: Unregister web
context: /singleton
20:10:32,022 INFO [stdout] (MSC service thread 1-3) Initiating removal of
jboss.test.myservice
20:10:32,037 INFO [stdout] (MSC service thread 1-3) Removal of jboss.test.myservice
complete
20:10:32,081 INFO [org.jboss.as.clustering.infinispan] (MSC service thread 1-5)
JBAS010282: Stopped default cache from cluster container
20:10:32,124 INFO [org.jboss.as.server.deployment] (MSC service thread 1-3) JBAS015877:
Stopped deployment singleton.war in 180ms
20:10:33,528 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC
service thread 1-4) ISPN000082: Stopping the RpcDispatcher
20:10:33,596 INFO [org.jboss.as.repository] (management-handler-thread - 3) JBAS014901:
Content removed from location
/home/jenkins/jenkins-work/workspace/as7-master-testsuite/testsuite/integration/clust/target/jbossas-clustering-udp-0/standalone/data/content/78/f00d7bef81599183e116df1064502efde42efc/content
20:10:33,599 INFO [org.jboss.as.server] (management-handler-thread - 3) JBAS018558:
Undeployed "singleton.war"
20:10:33,604 INFO
[org.jboss.arquillian.container.test.impl.client.container.ClientContainerController]
(main) Manual stopping of a server instance
20:10:33,936 INFO
[org.jboss.arquillian.container.test.impl.client.container.ClientContainerController]
(main) Manual stopping of a server instance
--
This message is automatically generated by JIRA.
If you think it was sent incorrectly, please contact your JIRA administrators:
https://issues.jboss.org/secure/ContactAdministrators!default.jspa
For more information on JIRA, see:
http://www.atlassian.com/software/jira