]
Scott Marlow commented on AS7-5699:
-----------------------------------
I haven't hit the ArrayIndexOutOfBoundsException after waiting 1.5 hours for progress
(probably won't see any progression until the http socket connection times out and
closes).
On the client side (surefire_boot log), we are in:
{quote}
"main" prio=10 tid=0x00007f8894007000 nid=0x3769 runnable [0x00007f889a04c000]
java.lang.Thread.State: RUNNABLE
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.read(SocketInputStream.java:129)
at
org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:166)
at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:90)
at
org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:281)
at
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:92)
at
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:62)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:254)
at
org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:289)
at
org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:252)
at
org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:191)
at
org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:300)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:127)
at
org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:712)
at
org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:517)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
at
org.jboss.as.test.clustering.ClusterHttpClientUtil.tryGet(ClusterHttpClientUtil.java:59)
at
org.jboss.as.test.clustering.ClusterHttpClientUtil.tryGet(ClusterHttpClientUtil.java:77)
at
org.jboss.as.test.clustering.ClusterHttpClientUtil.tryGet(ClusterHttpClientUtil.java:54)
at
org.jboss.as.test.clustering.cluster.web.ClusteredWebFailoverAbstractCase.testGracefulUndeployFailover(ClusteredWebFailoverAbstractCase.java:178)
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.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.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.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.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.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.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.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.test.impl.TestContextHandler.createClassContext(TestContextHandler.java:75)
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.createSuiteContext(TestContextHandler.java:60)
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.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)
{quote}
We seem to be invoking a http url against the tcp-0 node, so looking in thread dump for
first server node, I see this http thread that seems to be doing something or was:
{quote}
"http-/fccc:0:0:0:0:0:0:1%1:8080-1" daemon prio=10 tid=0x00007f1fc4029800
nid=0x4061 runnable [0x00007f1f4455e000]
java.lang.Thread.State: RUNNABLE
at
org.infinispan.AbstractDelegatingAdvancedCache.getDistributionManager(AbstractDelegatingAdvancedCache.java:108)
at
org.infinispan.affinity.KeyAffinityServiceImpl.getDistributionManager(KeyAffinityServiceImpl.java:356)
at
org.infinispan.affinity.KeyAffinityServiceImpl.isNodeInConsistentHash(KeyAffinityServiceImpl.java:351)
at
org.infinispan.affinity.KeyAffinityServiceImpl.getKeyForAddress(KeyAffinityServiceImpl.java:146)
at
org.jboss.as.clustering.web.infinispan.DistributedCacheManager.createSessionId(DistributedCacheManager.java:441)
at
org.jboss.as.web.session.DistributableSessionManager.generateSessionId(DistributableSessionManager.java:619)
at
org.jboss.as.web.session.DistributableSessionManager.createSessionInternal(DistributableSessionManager.java:589)
at
org.jboss.as.web.session.DistributableSessionManager.createSession(DistributableSessionManager.java:539)
at org.apache.catalina.connector.Request.doGetSession(Request.java:2628)
at org.apache.catalina.connector.Request.getSession(Request.java:2339)
at org.apache.catalina.connector.RequestFacade.getSession(RequestFacade.java:790)
at org.jboss.as.test.clustering.single.web.SimpleServlet.doGet(SimpleServlet.java:47)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:734)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:847)
at
org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:295)
at
org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:214)
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:230)
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:149)
at
org.jboss.modcluster.container.jbossweb.JBossWebContext$RequestListenerValve.event(JBossWebContext.java:67)
at
org.jboss.modcluster.container.jbossweb.JBossWebContext$RequestListenerValve.invoke(JBossWebContext.java:48)
at
org.jboss.as.web.session.ClusteredSessionValve.handleRequest(ClusteredSessionValve.java:134)
at org.jboss.as.web.session.ClusteredSessionValve.invoke(ClusteredSessionValve.java:99)
at org.jboss.as.web.session.JvmRouteValve.invoke(JvmRouteValve.java:92)
at org.jboss.as.web.session.LockingValve.invoke(LockingValve.java:64)
at
org.jboss.as.web.security.SecurityContextAssociationValve.invoke(SecurityContextAssociationValve.java:165)
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:145)
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:97)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:102)
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:336)
at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:856)
at
org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:652)
at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:919)
at java.lang.Thread.run(Thread.java:662)
{quote}
I'll capture more thread dumps of this first AS server node...
Exception in thread "KeyAffinityService Thread Pool -- 1"
java.lang.ArrayIndexOutOfBoundsException: 1
-----------------------------------------------------------------------------------------------------
Key: AS7-5699
URL:
https://issues.jboss.org/browse/AS7-5699
Project: Application Server 7
Issue Type: Bug
Components: Clustering
Environment: affects: snapshot
Reporter: Radoslav Husar
Assignee: Paul Ferraro
Fix For: 7.2.0.Alpha1
Attachments: jbossas-clustering-SYNC-tcp-0_threaddump.txt,
jbossas-clustering-SYNC-tcp-1_threaddump.txt, launch_threaddump.txt,
surefirebooter_threaddump.txt
After ISPN upgrade.
{noformat}
23:20:16,415 INFO [org.jboss.as.server.deployment] (MSC service thread 1-5) JBAS015876:
Starting deployment of "distributable.war"
23:20:16,424 INFO [org.jboss.as.server.deployment.scanner] (MSC service thread 1-4)
JBAS015012: Started FileSystemDeploymentService for directory
/home/smarlow/work/as7/testsuite/integration/clust/target/jbossas-clustering-SYNC-tcp-0/standalone/deployments
23:20:16,767 WARN [org.jgroups.stack.Configurator] (ServerService Thread Pool -- 63)
[JGRP00013] TP.discard_incompatible_packets has been deprecated: incompatible packets are
discarded anyway
23:20:16,957 INFO [stdout] (ServerService Thread Pool -- 63)
23:20:16,957 INFO [stdout] (ServerService Thread Pool -- 63)
-------------------------------------------------------------------
23:20:16,957 INFO [stdout] (ServerService Thread Pool -- 63) GMS: address=node-0/web,
cluster=web, physical address=fccc:0:0:0:0:0:0:1%1:7600
23:20:16,957 INFO [stdout] (ServerService Thread Pool -- 63)
-------------------------------------------------------------------
23:20:17,104 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC
service thread 1-5) ISPN000152: Passivation configured without an eviction policy being
selected. Only manually evicted entities will be passivated.
23:20:17,105 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC
service thread 1-6) ISPN000152: Passivation configured without an eviction policy being
selected. Only manually evicted entities will be passivated.
23:20:17,106 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC
service thread 1-5) ISPN000152: Passivation configured without an eviction policy being
selected. Only manually evicted entities will be passivated.
23:20:17,106 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC
service thread 1-6) ISPN000152: Passivation configured without an eviction policy being
selected. Only manually evicted entities will be passivated.
23:20:17,107 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC
service thread 1-4) ISPN000152: Passivation configured without an eviction policy being
selected. Only manually evicted entities will be passivated.
23:20:17,108 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC
service thread 1-4) ISPN000152: Passivation configured without an eviction policy being
selected. Only manually evicted entities will be passivated.
23:20:17,136 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport]
(ServerService Thread Pool -- 63) ISPN000078: Starting JGroups Channel
23:20:17,140 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport]
(ServerService Thread Pool -- 63) ISPN000094: Received new cluster view: [node-1/web|3]
[node-1/web, node-0/web]
23:20:17,140 INFO [org.infinispan.remoting.transport.jgroups.JGroupsTransport]
(ServerService Thread Pool -- 63) ISPN000079: Cache local address is node-0/web, physical
addresses are [fccc:0:0:0:0:0:0:1%1:7600]
23:20:17,141 INFO [org.infinispan.factories.GlobalComponentRegistry] (ServerService
Thread Pool -- 63) ISPN000128: Infinispan version: Infinispan 'Delirium'
5.2.0.Beta1
23:20:17,164 INFO [org.infinispan.factories.TransactionManagerFactory] (ServerService
Thread Pool -- 56) ISPN000161: Using a batchMode transaction manager
23:20:17,164 INFO [org.infinispan.factories.TransactionManagerFactory] (ServerService
Thread Pool -- 63) ISPN000161: Using a batchMode transaction manager
23:20:17,274 INFO [org.infinispan.jmx.CacheJmxRegistration] (ServerService Thread Pool
-- 56) ISPN000031: MBeans were successfully registered to the platform MBean server.
23:20:17,274 INFO [org.infinispan.jmx.CacheJmxRegistration] (ServerService Thread Pool
-- 63) ISPN000031: MBeans were successfully registered to the platform MBean server.
23:20:17,394 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 56)
JBAS010281: Started default-host/distributable cache from web container
23:20:17,396 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 63)
JBAS010281: Started repl cache from web container
23:20:17,405 INFO [org.jboss.as.clustering] (MSC service thread 1-5) JBAS010238: Number
of cluster members: 2
23:20:17,414 INFO [org.jboss.web] (MSC service thread 1-7) JBAS018210: Register web
context: /distributable
23:20:17,533 INFO [org.jboss.as.server] (Controller Boot Thread) JBAS018559: Deployed
"distributable.war"
23:20:17,688 INFO [org.jboss.as] (Controller Boot Thread) JBAS015961: Http management
interface listening on
http://fccc::1:9990/management
23:20:17,689 INFO [org.jboss.as] (Controller Boot Thread) JBAS015951: Admin console
listening on
http://fccc::1:9990
23:20:17,689 INFO [org.jboss.as] (Controller Boot Thread) JBAS015874: JBoss AS
7.2.0.Alpha1-SNAPSHOT "Steropes" started in 3445ms - Started 171 of 324 services
(152 services are passive or on-demand)
23:54:12,922 ERROR [stderr] (KeyAffinityService Thread Pool -- 1) Exception in thread
"KeyAffinityService Thread Pool -- 1" java.lang.ArrayIndexOutOfBoundsException:
1
23:54:12,923 ERROR [stderr] (KeyAffinityService Thread Pool -- 1) at
org.infinispan.distribution.ch.DefaultConsistentHash.locatePrimaryOwnerForSegment(DefaultConsistentHash.java:137)
23:54:12,923 ERROR [stderr] (KeyAffinityService Thread Pool -- 1) at
org.infinispan.distribution.ch.DefaultConsistentHash.locatePrimaryOwner(DefaultConsistentHash.java:152)
23:54:12,923 ERROR [stderr] (KeyAffinityService Thread Pool -- 1) at
org.infinispan.affinity.KeyAffinityServiceImpl.getAddressForKey(KeyAffinityServiceImpl.java:347)
23:54:12,924 ERROR [stderr] (KeyAffinityService Thread Pool -- 1) at
org.infinispan.affinity.KeyAffinityServiceImpl.access$700(KeyAffinityServiceImpl.java:59)
23:54:12,924 ERROR [stderr] (KeyAffinityService Thread Pool -- 1) at
org.infinispan.affinity.KeyAffinityServiceImpl$KeyGeneratorWorker.generateKeys(KeyAffinityServiceImpl.java:270)
23:54:12,924 ERROR [stderr] (KeyAffinityService Thread Pool -- 1) at
org.infinispan.affinity.KeyAffinityServiceImpl$KeyGeneratorWorker.run(KeyAffinityServiceImpl.java:242)
23:54:12,925 ERROR [stderr] (KeyAffinityService Thread Pool -- 1) at
java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
23:54:12,925 ERROR [stderr] (KeyAffinityService Thread Pool -- 1) at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
23:54:12,925 ERROR [stderr] (KeyAffinityService Thread Pool -- 1) at
java.lang.Thread.run(Thread.java:662)
23:54:12,925 ERROR [stderr] (KeyAffinityService Thread Pool -- 1) at
org.jboss.threads.JBossThread.run(JBossThread.java:122)
{noformat}
--
This message is automatically generated by JIRA.
If you think it was sent incorrectly, please contact your JIRA administrators
For more information on JIRA, see: