[jboss-jira] [JBoss JIRA] (AS7-5520) Warn "IllegalStateException: channel is not connected" on clean shutdown

Radoslav Husar (JIRA) jira-events at lists.jboss.org
Mon Sep 17 11:06:36 EDT 2012


    [ https://issues.jboss.org/browse/AS7-5520?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=12719148#comment-12719148 ] 

Radoslav Husar commented on AS7-5520:
-------------------------------------

Paul, ea8cdb092c74ec449e8bb9a3d627a90913d57225 does fix the issue, however "Exception acquiring ownership of" shows up again. Either way, I think this is good to go, much better than  per-JGroups-message  cost solution.

{noformat}
[JBossINF] 10:46:44,996 WARN  [org.apache.catalina.session.ManagerBase] JBAS018076: Exception expiring or passivating sesion wtkwN3oxiA-wjAjEqvjrLBwa.perf20: java.lang.RuntimeException: JBAS018060: Exception acquiring ownership of wtkwN3oxiA-wjAjEqvjrLBwa
[JBossINF] 	at org.jboss.as.web.session.ClusteredSession.expire(ClusteredSession.java:1270) [jboss-as-web-7.1.3.Final-SNAPSHOT.jar:7.1.3.Final-SNAPSHOT]
[JBossINF] 	at org.jboss.as.web.session.DistributableSessionManager.clearSessions(DistributableSessionManager.java:463) [jboss-as-web-7.1.3.Final-SNAPSHOT.jar:7.1.3.Final-SNAPSHOT]
[JBossINF] 	at org.jboss.as.web.session.DistributableSessionManager.stop(DistributableSessionManager.java:424) [jboss-as-web-7.1.3.Final-SNAPSHOT.jar:7.1.3.Final-SNAPSHOT]
[JBossINF] 	at org.apache.catalina.core.StandardContext.stop(StandardContext.java:3995) [jbossweb-7.0.17.Final.jar:]
[JBossINF] 	at org.jboss.as.web.deployment.WebDeploymentService.stop(WebDeploymentService.java:107) [jboss-as-web-7.1.3.Final-SNAPSHOT.jar:7.1.3.Final-SNAPSHOT]
[JBossINF] 	at org.jboss.msc.service.ServiceControllerImpl$StopTask.stopService(ServiceControllerImpl.java:1911)
[JBossINF] 	at org.jboss.msc.service.ServiceControllerImpl$StopTask.run(ServiceControllerImpl.java:1874)
[JBossINF] 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) [rt.jar:1.6.0_30]
[JBossINF] 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) [rt.jar:1.6.0_30]
[JBossINF] 	at java.lang.Thread.run(Thread.java:662) [rt.jar:1.6.0_30]
[JBossINF] Caused by: org.jboss.as.clustering.lock.TimeoutException: JBAS010213: Cannot acquire lock default-host/clusterbench/wtkwN3oxiA-wjAjEqvjrLBwa from cluster
[JBossINF] 	at org.jboss.as.clustering.lock.SharedLocalYieldingClusterLockManager.lock(SharedLocalYieldingClusterLockManager.java:439)
[JBossINF] 	at org.jboss.as.clustering.web.infinispan.DistributedCacheManager.acquireSessionOwnership(DistributedCacheManager.java:381)
[JBossINF] 	at org.jboss.as.web.session.ClusteredSession.acquireSessionOwnership(ClusteredSession.java:529) [jboss-as-web-7.1.3.Final-SNAPSHOT.jar:7.1.3.Final-SNAPSHOT]
[JBossINF] 	at org.jboss.as.web.session.ClusteredSession.expire(ClusteredSession.java:1267) [jboss-as-web-7.1.3.Final-SNAPSHOT.jar:7.1.3.Final-SNAPSHOT]
[JBossINF] 	... 9 more
{noformat}
                
> Warn "IllegalStateException: channel is not connected" on clean shutdown
> ------------------------------------------------------------------------
>
>                 Key: AS7-5520
>                 URL: https://issues.jboss.org/browse/AS7-5520
>             Project: Application Server 7
>          Issue Type: Bug
>          Components: Clustering
>            Reporter: Radoslav Husar
>            Assignee: Paul Ferraro
>             Fix For: 7.1.3.Final (EAP)
>
>
> The current fix in for AS7-5442 doesn't result in a timeout exception, Infinispan now checks whether channel is open however results in a WARN message.
> Looks like AS is closing the channel before the cache is stopped.
> {noformat}
> [JBossINF] 06:25:23,758 INFO  [org.jboss.as.clustering.infinispan] JBAS010282: Stopped default-host/clusterbench cache from web container
> [JBossINF] 06:25:23,767 INFO  [org.jboss.as.server.deployment] JBAS015877: Stopped deployment clusterbench-ee6-ejb.jar in 1061ms
> [JBossINF] 06:25:23,765 WARN  [org.infinispan.cacheviews.CacheViewsManagerImpl] ISPN000169: Error committing cache view 5 for cache default-host/clusterbench: org.infinispan.CacheException: java.lang.IllegalStateException: channel is not connected
> [JBossINF] 	at org.infinispan.util.Util.rewrapAsCacheException(Util.java:526) [infinispan-core-5.1.7-SNAPSHOT.jar:5.1.7-SNAPSHOT]
> [JBossINF] 	at org.infinispan.remoting.transport.jgroups.CommandAwareRpcDispatcher.invokeRemoteCommands(CommandAwareRpcDispatcher.java:139) [infinispan-core-5.1.7-SNAPSHOT.jar:5.1.7-SNAPSHOT]
> [JBossINF] 	at org.infinispan.remoting.transport.jgroups.JGroupsTransport.invokeRemotely(JGroupsTransport.java:492) [infinispan-core-5.1.7-SNAPSHOT.jar:5.1.7-SNAPSHOT]
> [JBossINF] 	at org.infinispan.cacheviews.CacheViewsManagerImpl.clusterCommitView(CacheViewsManagerImpl.java:378) [infinispan-core-5.1.7-SNAPSHOT.jar:5.1.7-SNAPSHOT]
> [JBossINF] 	at org.infinispan.cacheviews.CacheViewsManagerImpl.clusterInstallView(CacheViewsManagerImpl.java:272) [infinispan-core-5.1.7-SNAPSHOT.jar:5.1.7-SNAPSHOT]
> [JBossINF] 	at org.infinispan.cacheviews.CacheViewsManagerImpl$ViewInstallationTask.call(CacheViewsManagerImpl.java:894) [infinispan-core-5.1.7-SNAPSHOT.jar:5.1.7-SNAPSHOT]
> [JBossINF] 	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303) [rt.jar:1.6.0_30]
> [JBossINF] 	at java.util.concurrent.FutureTask.run(FutureTask.java:138) [rt.jar:1.6.0_30]
> [JBossINF] 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) [rt.jar:1.6.0_30]
> [JBossINF] 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) [rt.jar:1.6.0_30]
> [JBossINF] 	at java.lang.Thread.run(Thread.java:662) [rt.jar:1.6.0_30]
> [JBossINF] Caused by: java.lang.IllegalStateException: channel is not connected
> [JBossINF] 	at org.jgroups.blocks.MessageDispatcher$ProtocolAdapter.down(MessageDispatcher.java:619) [jgroups-3.0.14.Final.jar:3.0.14.Final]
> [JBossINF] 	at org.jgroups.blocks.RequestCorrelator.sendUnicastRequest(RequestCorrelator.java:193) [jgroups-3.0.14.Final.jar:3.0.14.Final]
> [JBossINF] 	at org.jgroups.blocks.UnicastRequest.sendRequest(UnicastRequest.java:44) [jgroups-3.0.14.Final.jar:3.0.14.Final]
> [JBossINF] 	at org.jgroups.blocks.Request.execute(Request.java:83) [jgroups-3.0.14.Final.jar:3.0.14.Final]
> [JBossINF] 	at org.jgroups.blocks.MessageDispatcher.sendMessageWithFuture(MessageDispatcher.java:419) [jgroups-3.0.14.Final.jar:3.0.14.Final]
> [JBossINF] 	at org.infinispan.remoting.transport.jgroups.CommandAwareRpcDispatcher.processCalls(CommandAwareRpcDispatcher.java:329) [infinispan-core-5.1.7-SNAPSHOT.jar:5.1.7-SNAPSHOT]
> [JBossINF] 	at org.infinispan.remoting.transport.jgroups.CommandAwareRpcDispatcher.invokeRemoteCommands(CommandAwareRpcDispatcher.java:132) [infinispan-core-5.1.7-SNAPSHOT.jar:5.1.7-SNAPSHOT]
> [JBossINF] 	... 9 more
> [JBossINF] 
> [JBossINF] 06:25:23,768 INFO  [org.jboss.as.server.deployment] JBAS015877: Stopped deployment clusterbench-ee6-web-granular.war in 1062ms
> [JBossINF] 06:25:23,769 INFO  [org.jboss.as.server.deployment] JBAS015877: Stopped deployment clusterbench-ee6-web.war in 1063ms
> [JBossINF] 06:25:23,770 INFO  [org.jboss.as.server.deployment] JBAS015877: Stopped deployment clusterbench-ee6.ear in 1076ms
> [JBossINF] 06:25:24,128 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] ISPN000082: Stopping the RpcDispatcher
> [JBossINF] 06:25:24,132 INFO  [org.jboss.as] JBAS015950: JBoss AS 7.1.3.Final-SNAPSHOT "Arges" stopped in 1337ms
> {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: http://www.atlassian.com/software/jira


More information about the jboss-jira mailing list