[jboss-jira] [JBoss JIRA] (AS7-5211) RemoteStatelessFailoverTestCase intermittently fails

RemoteStatelessFailoverTestCase intermittently fails ---------------------------------------------------- Key: AS7-5211 URL: https://issues.jboss.org/browse/AS7-5211 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.Alpha1 Regression org.jboss.as.test.clustering.cluster.ejb3.stateless.RemoteStatelessFailoverTestCase(UDP).testFailoverOnUndeploy Failing for the past 1 build (Since #4158 ) Took 28 sec. Error Message 0 Stacktrace java.lang.AssertionError: 0 at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.jboss.as.test.clustering.cluster.ejb3.stateless.RemoteStatelessFailoverTestCase.testFailoverOnUndeploy(RemoteStatelessFailoverTestCase.java:189) 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 08:35:30,628 INFO [org.jboss.arquillian.container.test.impl.client.container.ClientContainerController] (main) Manual starting of a server instance 08:35:30,637 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-0, -Djboss.node.name=node-0, -ea, -Djboss.home.dir=/home/jenkins/jenkins-work/workspace/as7-master-testsuite/testsuite/integration/clust/target/jbossas-clustering-udp-0, -Dorg.jboss.boot.log.file=/home/jenkins/jenkins-work/workspace/as7-master-testsuite/testsuite/integration/clust/target/jbossas-clustering-udp-0/standalone/log/boot.log, -Dlogging.configuration=file:/home/jenkins/jenkins-work/workspace/as7-master-testsuite/testsuite/integration/clust/target/jbossas-clustering-udp-0/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-0/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] 08:35:31,068 INFO [org.jboss.modules] JBoss Modules version 1.1.2.GA 08:35:31,334 INFO [org.jboss.msc] JBoss MSC version 1.0.2.GA 08:35:31,405 INFO [org.jboss.as] JBAS015899: JBoss AS 7.2.0.Alpha1-SNAPSHOT "Steropes" starting 08:35:33,322 INFO [org.xnio] XNIO Version 3.0.5.GA 08:35:33,331 INFO [org.xnio.nio] XNIO NIO Implementation Version 3.0.5.GA 08:35:33,340 INFO [org.jboss.remoting] JBoss Remoting version 3.2.8.GA 08:35:33,377 INFO [org.jboss.as.server] JBAS015888: Creating http management service using socket-binding (management-http) 08:35:33,428 INFO [org.jboss.as.logging] JBAS011502: Removing bootstrap log handlers 08:35:33,432 INFO [org.jboss.as.configadmin] (ServerService Thread Pool -- 29) JBAS016200: Activating ConfigAdmin Subsystem 08:35:33,461 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 34) JBAS010280: Activating Infinispan subsystem. 08:35:33,491 INFO [org.jboss.as.clustering.jgroups] (ServerService Thread Pool -- 38) JBAS010260: Activating JGroups subsystem. 08:35:33,516 INFO [org.jboss.as.naming] (ServerService Thread Pool -- 44) JBAS011800: Activating Naming Subsystem 08:35:33,544 INFO [org.jboss.as.osgi] (ServerService Thread Pool -- 45) JBAS011906: Activating OSGi Subsystem 08:35:33,545 INFO [org.jboss.as.security] (ServerService Thread Pool -- 50) JBAS013171: Activating Security Subsystem 08:35:33,567 INFO [org.jboss.as.connector.logging] (MSC service thread 1-2) JBAS010408: Starting JCA Subsystem (JBoss IronJacamar 1.0.11.Final) 08:35:33,588 INFO [org.jboss.as.security] (MSC service thread 1-5) JBAS013170: Current PicketBox version=4.0.9.Final 08:35:33,613 INFO [org.jboss.as.webservices] (ServerService Thread Pool -- 54) JBAS015537: Activating WebServices Extension 08:35:33,712 INFO [org.jboss.as.naming] (MSC service thread 1-1) JBAS011802: Starting Naming Service 08:35:33,748 INFO [org.jboss.as.mail.extension] (MSC service thread 1-4) JBAS015400: Bound mail session [java:jboss/mail/Default] 08:35:33,791 INFO [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 30) JBAS010403: Deploying JDBC-compliant driver class org.h2.Driver (version 1.3) 08:35:34,075 INFO [org.jboss.ws.common.management.AbstractServerConfig] (MSC service thread 1-4) JBoss Web Services - Stack CXF Server 4.1.0.Beta1 08:35:34,241 INFO [org.apache.coyote.ajp.AjpProtocol] (MSC service thread 1-3) Starting Coyote AJP/1.3 on ajp-/127.0.0.1:8009 08:35:34,247 INFO [org.apache.coyote.http11.Http11Protocol] (MSC service thread 1-6) Starting Coyote HTTP/1.1 on http-/127.0.0.1:8080 08:35:34,393 INFO [org.jboss.modcluster.ModClusterService] (ServerService Thread Pool -- 56) Initializing mod_cluster 1.2.1.Final 08:35:34,468 INFO [org.jboss.modcluster.advertise.impl.AdvertiseListenerImpl] (ServerService Thread Pool -- 56) Listening to proxy advertisements on 224.0.1.105:23,364 08:35:34,629 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. 08:35:34,643 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. 08:35:34,753 INFO [org.jboss.as.server.deployment.scanner] (MSC service thread 1-1) JBAS015012: Started FileSystemDeploymentService for directory /home/jenkins/jenkins-work/workspace/as7-master-testsuite/testsuite/integration/clust/target/jbossas-clustering-udp-0/standalone/deployments 08:35:34,797 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-1) JBAS010400: Bound data source [java:jboss/datasources/ExampleDS] 08:35:34,799 INFO [org.jboss.as.remoting] (MSC service thread 1-6) JBAS017100: Listening on 127.0.0.1:9999 08:35:34,799 INFO [org.jboss.as.remoting] (MSC service thread 1-2) JBAS017100: Listening on 127.0.0.1:4447 08:35:34,806 INFO [org.jboss.as.server.deployment] (MSC service thread 1-5) JBAS015876: Starting deployment of "remote-ejb-client-stateless-bean-failover-test.jar" 08:35:35,008 INFO [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011006: OSGi Framework - 2.0.0.CR5 08:35:35,170 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-1) JNDI bindings for session bean named StatelessBean in deployment unit deployment "remote-ejb-client-stateless-bean-failover-test.jar" are as follows: java:global/remote-ejb-client-stateless-bean-failover-test/StatelessBean!org.jboss.as.test.clustering.cluster.ejb3.stateless.bean.Stateless java:app/remote-ejb-client-stateless-bean-failover-test/StatelessBean!org.jboss.as.test.clustering.cluster.ejb3.stateless.bean.Stateless java:module/StatelessBean!org.jboss.as.test.clustering.cluster.ejb3.stateless.bean.Stateless java:jboss/exported/remote-ejb-client-stateless-bean-failover-test/StatelessBean!org.jboss.as.test.clustering.cluster.ejb3.stateless.bean.Stateless java:global/remote-ejb-client-stateless-bean-failover-test/StatelessBean java:app/remote-ejb-client-stateless-bean-failover-test/StatelessBean java:module/StatelessBean 08:35:35,192 INFO [org.jboss.as.osgi] (MSC service thread 1-2) JBAS011907: Register module: deployment.remote-ejb-client-stateless-bean-failover-test.jar:main 08:35:35,921 WARN [org.jgroups.protocols.UDP] (ServerService Thread Pool -- 56) send buffer of socket java.net.DatagramSocket@2a4df9a0 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) 08:35:35,921 WARN [org.jgroups.protocols.UDP] (ServerService Thread Pool -- 56) receive buffer of socket java.net.DatagramSocket@2a4df9a0 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) 08:35:35,922 WARN [org.jgroups.protocols.UDP] (ServerService Thread Pool -- 56) send buffer of socket java.net.MulticastSocket@2f46ccac 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) 08:35:35,922 WARN [org.jgroups.protocols.UDP] (ServerService Thread Pool -- 56) receive buffer of socket java.net.MulticastSocket@2f46ccac 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) 08:35:35,932 INFO [stdout] (ServerService Thread Pool -- 56) 08:35:35,933 INFO [stdout] (ServerService Thread Pool -- 56) ------------------------------------------------------------------- 08:35:35,934 INFO [stdout] (ServerService Thread Pool -- 56) GMS: address=node-0/ejb, cluster=ejb, physical address=127.0.0.1:55200 08:35:35,934 INFO [stdout] (ServerService Thread Pool -- 56) ------------------------------------------------------------------- 08:35:38,178 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (ServerService Thread Pool -- 56) ISPN000078: Starting JGroups Channel 08:35:38,202 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (ServerService Thread Pool -- 56) ISPN000094: Received new cluster view: [node-0/ejb|0] [node-0/ejb] 08:35:38,206 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (ServerService Thread Pool -- 56) ISPN000079: Cache local address is node-0/ejb, physical addresses are [127.0.0.1:55200] 08:35:38,225 INFO [org.infinispan.factories.GlobalComponentRegistry] (ServerService Thread Pool -- 56) ISPN000128: Infinispan version: Infinispan 'Brahma' 5.1.5.FINAL 08:35:38,775 INFO [org.infinispan.jmx.CacheJmxRegistration] (ServerService Thread Pool -- 56) ISPN000031: MBeans were successfully registered to the platform mbean server. 08:35:38,810 INFO [org.infinispan.jmx.CacheJmxRegistration] (ServerService Thread Pool -- 57) ISPN000031: MBeans were successfully registered to the platform mbean server. 08:35:38,830 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 56) JBAS010281: Started remote-connector-client-mappings cache from ejb container 08:35:38,830 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 57) JBAS010281: Started repl cache from ejb container 08:35:38,869 INFO [org.jboss.as.clustering] (MSC service thread 1-5) JBAS010238: Number of cluster members: 1 08:35:38,972 INFO [org.jboss.as.server] (Controller Boot Thread) JBAS018559: Deployed "remote-ejb-client-stateless-bean-failover-test.jar" 08:35:39,003 INFO [org.jboss.as] (Controller Boot Thread) JBAS015961: Http management interface listening on http://127.0.0.1:9990/management 08:35:39,005 INFO [org.jboss.as] (Controller Boot Thread) JBAS015951: Admin console listening on http://127.0.0.1:9990 08:35:39,007 INFO [org.jboss.as] (Controller Boot Thread) JBAS015874: JBoss AS 7.2.0.Alpha1-SNAPSHOT "Steropes" started in 8234ms - Started 178 of 327 services (148 services are passive or on-demand) 08:35:39,145 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-0/standalone/data/content/6b/def388812117e6781d500d1da007fe72b74f11/content 08:35:40,010 INFO [org.jboss.ejb.client.remoting] (Remoting "config-based-ejb-client-endpoint" task-1) EJBCLIENT000017: Received server version 1 and marshalling strategies [river] 08:35:40,015 INFO [org.jboss.ejb.client.remoting] (main) EJBCLIENT000013: Successful version handshake completed for receiver context EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@10c1c428, receiver=Remoting connection EJB receiver [connection=Remoting connection <3dffb2eb>,channel=jboss.ejb,nodename=node-0]} on channel Channel ID e30d980a (outbound) of Remoting connection 3a33a6b8 to /127.0.0.1:4447 08:35:40,315 INFO [org.jboss.as.test.clustering.cluster.ejb3.stateless.bean.StatelessBean] (EJB default - 1) StatelessBean.getNodeName() was called on node: node-0 08:35:40,370 INFO [org.jboss.arquillian.container.test.impl.client.container.ClientContainerController] (main) Manual starting of a server instance 08:35:40,376 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] 08:35:40,892 INFO [org.jboss.modules] JBoss Modules version 1.1.2.GA 08:35:41,143 INFO [org.jboss.msc] JBoss MSC version 1.0.2.GA 08:35:41,242 INFO [org.jboss.as] JBAS015899: JBoss AS 7.2.0.Alpha1-SNAPSHOT "Steropes" starting 08:35:43,496 INFO [org.jboss.as.server] JBAS015888: Creating http management service using socket-binding (management-http) 08:35:43,498 INFO [org.xnio] XNIO Version 3.0.5.GA 08:35:43,509 INFO [org.xnio.nio] XNIO NIO Implementation Version 3.0.5.GA 08:35:43,520 INFO [org.jboss.remoting] JBoss Remoting version 3.2.8.GA 08:35:43,555 INFO [org.jboss.as.logging] JBAS011502: Removing bootstrap log handlers 08:35:43,558 INFO [org.jboss.as.configadmin] (ServerService Thread Pool -- 29) JBAS016200: Activating ConfigAdmin Subsystem 08:35:43,601 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 34) JBAS010280: Activating Infinispan subsystem. 08:35:43,622 INFO [org.jboss.as.clustering.jgroups] (ServerService Thread Pool -- 38) JBAS010260: Activating JGroups subsystem. 08:35:43,727 INFO [org.jboss.as.security] (ServerService Thread Pool -- 50) JBAS013171: Activating Security Subsystem 08:35:43,776 INFO [org.jboss.as.osgi] (ServerService Thread Pool -- 45) JBAS011906: Activating OSGi Subsystem 08:35:43,795 INFO [org.jboss.as.naming] (ServerService Thread Pool -- 44) JBAS011800: Activating Naming Subsystem 08:35:43,802 INFO [org.jboss.as.connector.logging] (MSC service thread 1-6) JBAS010408: Starting JCA Subsystem (JBoss IronJacamar 1.0.11.Final) 08:35:43,861 INFO [org.jboss.as.webservices] (ServerService Thread Pool -- 54) JBAS015537: Activating WebServices Extension 08:35:43,865 INFO [org.jboss.as.security] (MSC service thread 1-6) JBAS013170: Current PicketBox version=4.0.9.Final 08:35:44,026 INFO [org.jboss.as.naming] (MSC service thread 1-3) JBAS011802: Starting Naming Service 08:35:44,034 INFO [org.jboss.as.mail.extension] (MSC service thread 1-1) JBAS015400: Bound mail session [java:jboss/mail/Default] 08:35:44,156 INFO [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 30) JBAS010403: Deploying JDBC-compliant driver class org.h2.Driver (version 1.3) 08:35:44,345 INFO [org.jboss.ws.common.management.AbstractServerConfig] (MSC service thread 1-5) JBoss Web Services - Stack CXF Server 4.1.0.Beta1 08:35:44,595 INFO [org.apache.coyote.http11.Http11Protocol] (MSC service thread 1-2) Starting Coyote HTTP/1.1 on http-/127.0.0.1:8180 08:35:44,600 INFO [org.apache.coyote.ajp.AjpProtocol] (MSC service thread 1-6) Starting Coyote AJP/1.3 on ajp-/127.0.0.1:8109 08:35:44,784 INFO [org.jboss.modcluster.ModClusterService] (ServerService Thread Pool -- 56) Initializing mod_cluster 1.2.1.Final 08:35:44,904 INFO [org.jboss.modcluster.advertise.impl.AdvertiseListenerImpl] (ServerService Thread Pool -- 56) Listening to proxy advertisements on 224.0.1.105:23,364 08:35:45,146 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. 08:35:45,166 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. 08:35:45,208 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-4) JBAS010400: Bound data source [java:jboss/datasources/ExampleDS] 08:35:45,259 INFO [org.jboss.as.server.deployment.scanner] (MSC service thread 1-1) JBAS015012: Started FileSystemDeploymentService for directory /home/jenkins/jenkins-work/workspace/as7-master-testsuite/testsuite/integration/clust/target/jbossas-clustering-udp-1/standalone/deployments 08:35:45,264 INFO [org.jboss.as.remoting] (MSC service thread 1-3) JBAS017100: Listening on 127.0.0.1:10099 08:35:45,264 INFO [org.jboss.as.remoting] (MSC service thread 1-5) JBAS017100: Listening on 127.0.0.1:4547 08:35:45,404 INFO [org.jboss.as] (Controller Boot Thread) JBAS015961: Http management interface listening on http://127.0.0.1:10090/management 08:35:45,405 INFO [org.jboss.as] (Controller Boot Thread) JBAS015951: Admin console listening on http://127.0.0.1:10090 08:35:45,405 INFO [org.jboss.as] (Controller Boot Thread) JBAS015874: JBoss AS 7.2.0.Alpha1-SNAPSHOT "Steropes" started in 4816ms - Started 138 of 285 services (146 services are passive or on-demand) 08:35:47,066 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/7c/2ae6189d08460761feeaac9960161b5c8d9963/content 08:35:47,123 INFO [org.jboss.as.server.deployment] (MSC service thread 1-5) JBAS015876: Starting deployment of "remote-ejb-client-stateless-bean-failover-test.jar" 08:35:47,423 INFO [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011006: OSGi Framework - 2.0.0.CR5 08:35:47,772 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-4) JNDI bindings for session bean named StatelessBean in deployment unit deployment "remote-ejb-client-stateless-bean-failover-test.jar" are as follows: java:global/remote-ejb-client-stateless-bean-failover-test/StatelessBean!org.jboss.as.test.clustering.cluster.ejb3.stateless.bean.Stateless java:app/remote-ejb-client-stateless-bean-failover-test/StatelessBean!org.jboss.as.test.clustering.cluster.ejb3.stateless.bean.Stateless java:module/StatelessBean!org.jboss.as.test.clustering.cluster.ejb3.stateless.bean.Stateless java:jboss/exported/remote-ejb-client-stateless-bean-failover-test/StatelessBean!org.jboss.as.test.clustering.cluster.ejb3.stateless.bean.Stateless java:global/remote-ejb-client-stateless-bean-failover-test/StatelessBean java:app/remote-ejb-client-stateless-bean-failover-test/StatelessBean java:module/StatelessBean 08:35:47,833 INFO [org.jboss.as.osgi] (MSC service thread 1-3) JBAS011907: Register module: deployment.remote-ejb-client-stateless-bean-failover-test.jar:main 08:35:49,229 WARN [org.jgroups.protocols.UDP] (ServerService Thread Pool -- 26) send buffer of socket java.net.DatagramSocket@4fbf7435 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) 08:35:49,230 WARN [org.jgroups.protocols.UDP] (ServerService Thread Pool -- 26) receive buffer of socket java.net.DatagramSocket@4fbf7435 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) 08:35:49,231 WARN [org.jgroups.protocols.UDP] (ServerService Thread Pool -- 26) send buffer of socket java.net.MulticastSocket@6d3c121b 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) 08:35:49,232 WARN [org.jgroups.protocols.UDP] (ServerService Thread Pool -- 26) receive buffer of socket java.net.MulticastSocket@6d3c121b 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) 08:35:49,236 INFO [stdout] (ServerService Thread Pool -- 26) 08:35:49,237 INFO [stdout] (ServerService Thread Pool -- 26) ------------------------------------------------------------------- 08:35:49,237 INFO [stdout] (ServerService Thread Pool -- 26) GMS: address=node-1/ejb, cluster=ejb, physical address=127.0.0.1:55300 08:35:49,237 INFO [stdout] (ServerService Thread Pool -- 26) ------------------------------------------------------------------- 08:35:49,359 INFO [org.jboss.as.clustering] (ViewHandler,ejb,node-0/ejb) JBAS010225: New cluster view for partition ejb (id: 1, delta: 1, merge: false) : [node-0/ejb, node-1/ejb] 08:35:49,362 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (ViewHandler,ejb,node-0/ejb) ISPN000094: Received new cluster view: [node-0/ejb|1] [node-0/ejb, node-1/ejb] 08:35:49,592 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (ServerService Thread Pool -- 28) ISPN000078: Starting JGroups Channel 08:35:49,629 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (ServerService Thread Pool -- 28) ISPN000094: Received new cluster view: [node-0/ejb|1] [node-0/ejb, node-1/ejb] 08:35:49,633 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (ServerService Thread Pool -- 28) ISPN000079: Cache local address is node-1/ejb, physical addresses are [127.0.0.1:55300] 08:35:49,654 INFO [org.infinispan.factories.GlobalComponentRegistry] (ServerService Thread Pool -- 28) ISPN000128: Infinispan version: Infinispan 'Brahma' 5.1.5.FINAL 08:35:50,201 INFO [org.infinispan.jmx.CacheJmxRegistration] (ServerService Thread Pool -- 28) ISPN000031: MBeans were successfully registered to the platform mbean server. 08:35:50,208 INFO [org.infinispan.jmx.CacheJmxRegistration] (ServerService Thread Pool -- 26) ISPN000031: MBeans were successfully registered to the platform mbean server. 08:35:50,516 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 26) JBAS010281: Started repl cache from ejb container 08:35:50,567 INFO [org.jboss.as.clustering] (MSC service thread 1-3) JBAS010238: Number of cluster members: 2 08:35:50,646 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 28) JBAS010281: Started remote-connector-client-mappings cache from ejb container 08:35:50,802 INFO [org.jboss.ejb.client.remoting] (Remoting "config-based-ejb-client-endpoint" task-3) EJBCLIENT000017: Received server version 1 and marshalling strategies [river] 08:35:50,805 INFO [org.jboss.ejb.client.remoting] (ejb-client-cluster-node-connection-creation-3-thread-2) EJBCLIENT000013: Successful version handshake completed for receiver context EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@10c1c428, receiver=Remoting connection EJB receiver [connection=Remoting connection <7cb8f891>,channel=jboss.ejb,nodename=node-1]} on channel Channel ID a323335d (outbound) of Remoting connection 1ca53e68 to /127.0.0.1:4547 08:35:50,893 INFO [org.jboss.ejb.client.remoting] (Remoting "config-based-ejb-client-endpoint" task-2) EJBCLIENT000017: Received server version 1 and marshalling strategies [river] 08:35:50,896 INFO [org.jboss.ejb.client.remoting] (ejb-client-cluster-node-connection-creation-3-thread-1) EJBCLIENT000013: Successful version handshake completed for receiver context EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@10c1c428, receiver=Remoting connection EJB receiver [connection=Remoting connection <3a18cecd>,channel=jboss.ejb,nodename=node-1]} on channel Channel ID de57bd07 (outbound) of Remoting connection 06b40443 to /127.0.0.1:4547 08:35:50,989 INFO [org.jboss.ejb.client.remoting] (Remoting "config-based-ejb-client-endpoint" task-2) EJBCLIENT000017: Received server version 1 and marshalling strategies [river] 08:35:50,993 INFO [org.jboss.ejb.client.remoting] (ejb-client-cluster-node-connection-creation-3-thread-3) EJBCLIENT000013: Successful version handshake completed for receiver context EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@10c1c428, receiver=Remoting connection EJB receiver [connection=Remoting connection <4f57011e>,channel=jboss.ejb,nodename=node-1]} on channel Channel ID 9711e3d8 (outbound) of Remoting connection 71591b4d to /127.0.0.1:4547 08:35:51,095 INFO [org.jboss.as.server] (management-handler-thread - 2) JBAS018559: Deployed "remote-ejb-client-stateless-bean-failover-test.jar" 08:35:56,751 INFO [org.jboss.as.test.clustering.cluster.ejb3.stateless.bean.StatelessBean] (EJB default - 1) StatelessBean.getNodeName() was called on node: node-1 08:35:56,779 INFO [org.jboss.as.test.clustering.cluster.ejb3.stateless.bean.StatelessBean] (EJB default - 2) StatelessBean.getNodeName() was called on node: node-1 08:35:56,795 INFO [org.jboss.as.test.clustering.cluster.ejb3.stateless.bean.StatelessBean] (EJB default - 3) StatelessBean.getNodeName() was called on node: node-1 08:35:56,806 INFO [org.jboss.as.test.clustering.cluster.ejb3.stateless.bean.StatelessBean] (EJB default - 4) StatelessBean.getNodeName() was called on node: node-1 08:35:56,818 INFO [org.jboss.as.test.clustering.cluster.ejb3.stateless.bean.StatelessBean] (EJB default - 5) StatelessBean.getNodeName() was called on node: node-1 08:35:56,840 INFO [org.jboss.as.test.clustering.cluster.ejb3.stateless.bean.StatelessBean] (EJB default - 6) StatelessBean.getNodeName() was called on node: node-1 08:35:56,851 INFO [org.jboss.as.test.clustering.cluster.ejb3.stateless.bean.StatelessBean] (EJB default - 7) StatelessBean.getNodeName() was called on node: node-1 08:35:56,863 INFO [org.jboss.as.test.clustering.cluster.ejb3.stateless.bean.StatelessBean] (EJB default - 8) StatelessBean.getNodeName() was called on node: node-1 08:35:56,876 INFO [org.jboss.as.test.clustering.cluster.ejb3.stateless.bean.StatelessBean] (EJB default - 9) StatelessBean.getNodeName() was called on node: node-1 08:35:56,886 INFO [org.jboss.as.test.clustering.cluster.ejb3.stateless.bean.StatelessBean] (EJB default - 10) StatelessBean.getNodeName() was called on node: node-1 08:35:56,996 INFO [org.jboss.as.osgi] (MSC service thread 1-3) JBAS011908: Unregister module: deployment.remote-ejb-client-stateless-bean-failover-test.jar:main 08:35:57,036 INFO [org.infinispan.eviction.PassivationManagerImpl] (MSC service thread 1-4) ISPN000029: Passivating all entries to disk 08:35:57,039 INFO [org.infinispan.eviction.PassivationManagerImpl] (MSC service thread 1-4) ISPN000030: Passivated 0 entries in 2 milliseconds 08:35:57,060 INFO [org.jboss.as.server.deployment] (MSC service thread 1-6) JBAS015877: Stopped deployment remote-ejb-client-stateless-bean-failover-test.jar in 145ms 08:35:57,065 INFO [org.jboss.as.clustering.infinispan] (MSC service thread 1-4) JBAS010282: Stopped repl cache from ejb container 08:35:57,090 INFO [org.jboss.as.clustering.infinispan] (MSC service thread 1-5) JBAS010282: Stopped remote-connector-client-mappings cache from ejb container 08:35:57,435 INFO [org.jboss.as.clustering] (Incoming-5,null) JBAS010225: New cluster view for partition ejb (id: 2, delta: -1, merge: false) : [node-1/ejb] 08:35:57,441 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (Incoming-5,null) ISPN000094: Received new cluster view: [node-1/ejb|2] [node-1/ejb] 08:35:57,728 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-2) ISPN000082: Stopping the RpcDispatcher 08:35:57,901 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/8b/b9ae5ad8eff42131549c1ab848993c2a43438c/content 08:35:57,903 INFO [org.jboss.as.server] (management-handler-thread - 3) JBAS018558: Undeployed "remote-ejb-client-stateless-bean-failover-test.jar" 08:35:57,908 INFO [org.jboss.arquillian.container.test.impl.client.container.ClientContainerController] (main) Manual stopping of a server instance 08:35:58,092 INFO [org.jboss.ejb.client.remoting] (Remoting "config-based-ejb-client-endpoint" task-2) EJBCLIENT000016: Channel Channel ID e30d980a (outbound) of Remoting connection 3a33a6b8 to /127.0.0.1:4447 can no longer process messages 08:35:58,240 INFO [org.jboss.as.osgi] (MSC service thread 1-2) JBAS011908: Unregister module: deployment.remote-ejb-client-stateless-bean-failover-test.jar:main 08:35:58,262 INFO [org.infinispan.eviction.PassivationManagerImpl] (MSC service thread 1-4) ISPN000029: Passivating all entries to disk 08:35:58,274 INFO [org.jboss.as.clustering.infinispan] (MSC service thread 1-5) JBAS010282: Stopped remote-connector-client-mappings cache from ejb container 08:35:58,277 INFO [org.infinispan.eviction.PassivationManagerImpl] (MSC service thread 1-4) ISPN000030: Passivated 0 entries in 14 milliseconds 08:35:58,280 INFO [org.jboss.as.server.deployment] (MSC service thread 1-1) JBAS015877: Stopped deployment remote-ejb-client-stateless-bean-failover-test.jar in 70ms 08:35:58,286 INFO [org.jboss.as.clustering.infinispan] (MSC service thread 1-4) JBAS010282: Stopped repl cache from ejb container 08:35:58,557 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-2) ISPN000082: Stopping the RpcDispatcher 08:35:58,613 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/7c/2ae6189d08460761feeaac9960161b5c8d9963/content 08:35:58,616 INFO [org.jboss.as.server] (management-handler-thread - 3) JBAS018558: Undeployed "remote-ejb-client-stateless-bean-failover-test.jar" 08:35:58,619 INFO [org.jboss.arquillian.container.test.impl.client.container.ClientContainerController] (main) Manual stopping of a server instance 08:35:58,806 INFO [org.jboss.ejb.client.remoting] (Remoting "config-based-ejb-client-endpoint" task-2) EJBCLIENT000016: Channel Channel ID 9711e3d8 (outbound) of Remoting connection 71591b4d to /127.0.0.1:4547 can no longer process messages 08:35:58,813 INFO [org.jboss.ejb.client.remoting] (Remoting "config-based-ejb-client-endpoint" task-4) EJBCLIENT000016: Channel Channel ID de57bd07 (outbound) of Remoting connection 06b40443 to /127.0.0.1:4547 can no longer process messages 08:35:58,823 INFO [org.jboss.ejb.client.remoting] (Remoting "config-based-ejb-client-endpoint" task-1) EJBCLIENT000016: Channel Channel ID a323335d (outbound) of Remoting connection 1ca53e68 to /127.0.0.1:4547 can no longer process messages