Uploaded image for project: 'Application Server 7'
  1. Application Server 7
  2. AS7-5210

SingletonTestCase intermittently fails

    XMLWordPrintable

Details

    Description

      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

      Attachments

        Activity

          People

            pferraro@redhat.com Paul Ferraro
            jgreene@redhat.com Jason Greene
            Votes:
            0 Vote for this issue
            Watchers:
            3 Start watching this issue

            Dates

              Created:
              Updated:
              Resolved: