[prev in list] [next in list] [prev in thread] [next in thread] 

List:       activemq-dev
Subject:    [jira] [Commented] (AMQ-4105) InactivityIOException exception leading to ServiceMix not functioning
From:       "Gary Tully (JIRA)" <jira () apache ! org>
Date:       2012-10-31 13:37:13
Message-ID: 811749032.50273.1351690633229.JavaMail.jiratomcat () arcas
[Download RAW message or body]


    [ https://issues.apache.org/jira/browse/AMQ-4105?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13487772#comment-13487772 \
] 

Gary Tully commented on AMQ-4105:
---------------------------------

does this look like a problem with the connection pool, not removing connections that \
error out?

Enable debug logging and tracing on your transport connector and you will see \
inactivity timeout negotiation. you should be able to disable it but maybe there is \
some issue in 5.4.2 in that regard. The logging will give u full information. As a \
workaround, u may need to configure the 0 inactivity timeout on the client side \
broker url tcp transport.  
> InactivityIOException exception leading to ServiceMix not functioning
> ---------------------------------------------------------------------
> 
> Key: AMQ-4105
> URL: https://issues.apache.org/jira/browse/AMQ-4105
> Project: ActiveMQ
> Issue Type: Bug
> Components: Broker
> Affects Versions: 5.4.2
> Environment: OS: SunOS 5.10 Generic_147440-23 sun4v sparc SUNW,Netra-T5440
> HW: Sun sparc Netra T5440
> ActiveMq version: 5.4.2
> ServiceMix version: 4.3.0
> Java version: 1.5.0_32
> Reporter: Mithun Sunku
> Priority: Critical
> Attachments: servicemix.log.zip
> 
> 
> We are using ActiveMQ broker with ServiceMix and have observed following \
> InactivityIOException being reported in ServiceMix logs and ActiveMq closes \
> MessageProducer and MessageComsumer. Then JMS related \
> activemq.ConnectionFailedExceptions are observed and the ServiceMix stops \
> functioning and clients are not able to register for topics and get any data from \
> ServiceMix. Transport failed: org.apache.activemq.transport.InactivityIOException: \
> Channel was inactive for too (>30000) long: /127.0.0.1:44650  Based on the \
> information provided in forum, we have tried to disable Inactivity Monitor in the \
> ActiveMq-broker.xml file available in ServiceMix/etc directory, however Inactivity \
> monitor is not changed from default value of 30000.  ActiveMq-broker.xml from our \
> setup: <broker>
> <!-- The transport connectors ActiveMQ will listen to -->
> <transportConnectors>
> <transportConnector name="openwire" \
> uri="tcp://localhost:61616?wireFormat.maxInactivityDuration=0"/> \
> <transportConnector name="stomp" uri="stomp://localhost:61613"/> \
> </transportConnectors> </broker>
> Please let us know what has caused InactivityTimeout exception and how to reproduce \
> this issue and how this issue will be resolved. ServiceMix Logs:
> [2012-10-09 03:26:24,964] | INFO  | InactivityMonitor Async Task: \
> java.util.concurrent.ThreadPoolExecutor$Worker@49754b | Transport                | \
> emq.broker.TransportConnection  238 | Transport failed: \
> org.apache.activemq.transport.InactivityIOException: Channel was inactive for too \
> (>30000) long: /127.0.0.1:44650 [2012-10-09 03:26:25,306] | INFO  | ActiveMQ \
> Transport: tcp:///127.0.0.1:44705 | Transport                | \
> emq.broker.TransportConnection  238 | Transport failed: java.io.EOFException \
> [2012-10-09 03:26:26,443] | WARN  | \
> pool-component.servicemix-wsn2005.provider-thread-36 | JmsPublisher             | \
>                 ervicemix.wsn.jms.JmsPublisher   97 | Error dispatching message
> javax.jms.IllegalStateException: The producer is closed
> 	at org.apache.activemq.ActiveMQMessageProducer.checkClosed(ActiveMQMessageProducer.java:169)
>   at org.apache.activemq.ActiveMQMessageProducerSupport.getDeliveryMode(ActiveMQMessageProducerSupport.java:148)
>   at org.apache.activemq.pool.PooledProducer.<init>(PooledProducer.java:44)
> 	at org.apache.activemq.pool.PooledSession.createProducer(PooledSession.java:278)
> 	at org.apache.servicemix.wsn.jms.JmsPublisher.notify(JmsPublisher.java:89)[166:servicemix-wsn2005:2011.01.0]
>   at org.apache.servicemix.wsn.AbstractNotificationBroker.handleNotify(AbstractNotificationBroker.java:134)[166:servicemix-wsn2005:2011.01.0]
>   at org.apache.servicemix.wsn.AbstractNotificationBroker.notify(AbstractNotificationBroker.java:126)[166:servicemix-wsn2005:2011.01.0]
>   at sun.reflect.GeneratedMethodAccessor38.invoke(Unknown Source)
> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)[:1.6.0_33]
> 	at java.lang.reflect.Method.invoke(Unknown Source)[:1.6.0_33]
> 	at org.apache.servicemix.wsn.component.WSNEndpoint.process(WSNEndpoint.java:166)[166:servicemix-wsn2005:2011.01.0]
>   at org.apache.servicemix.common.AsyncBaseLifeCycle.doProcess(AsyncBaseLifeCycle.java:651)[121:servicemix-common:2011.01.0]
>   at org.apache.servicemix.common.AsyncBaseLifeCycle.processExchange(AsyncBaseLifeCycle.java:606)[121:servicemix-common:2011.01.0]
>   at org.apache.servicemix.common.AsyncBaseLifeCycle.processExchangeInTx(AsyncBaseLifeCycle.java:501)[121:servicemix-common:2011.01.0]
>   at org.apache.servicemix.common.AsyncBaseLifeCycle$2.run(AsyncBaseLifeCycle.java:370)[121:servicemix-common:2011.01.0]
>   at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown \
> Source)[:1.6.0_33]  at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown \
> Source)[:1.6.0_33]  at java.lang.Thread.run(Unknown Source)[:1.6.0_33]
> [2012-10-09 03:26:26,631] | INFO  | \
> pool-component.servicemix-wsn2005.provider-thread-37 | JmsPullPoint             | \
>                 ervicemix.wsn.jms.JmsPullPoint  125 | Error retrieving messages
> javax.jms.IllegalStateException: The Consumer is closed
> 	at org.apache.activemq.ActiveMQMessageConsumer.checkClosed(ActiveMQMessageConsumer.java:791)
>   at org.apache.activemq.ActiveMQMessageConsumer.receiveNoWait(ActiveMQMessageConsumer.java:600)
>   at org.apache.servicemix.wsn.jms.JmsPullPoint.getMessages(JmsPullPoint.java:114)[166:servicemix-wsn2005:2011.01.0]
>   at org.apache.servicemix.wsn.AbstractPullPoint.getMessages(AbstractPullPoint.java:92)[166:servicemix-wsn2005:2011.01.0]
>   at sun.reflect.GeneratedMethodAccessor39.invoke(Unknown Source)
> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)[:1.6.0_33]
> 	at java.lang.reflect.Method.invoke(Unknown Source)[:1.6.0_33]
> 	at org.apache.servicemix.wsn.component.WSNEndpoint.process(WSNEndpoint.java:166)[166:servicemix-wsn2005:2011.01.0]
>   at org.apache.servicemix.common.AsyncBaseLifeCycle.doProcess(AsyncBaseLifeCycle.java:651)[121:servicemix-common:2011.01.0]
>   at org.apache.servicemix.common.AsyncBaseLifeCycle.processExchange(AsyncBaseLifeCycle.java:606)[121:servicemix-common:2011.01.0]
>   at org.apache.servicemix.common.AsyncBaseLifeCycle.processExchangeInTx(AsyncBaseLifeCycle.java:501)[121:servicemix-common:2011.01.0]
>   at org.apache.servicemix.common.AsyncBaseLifeCycle$2.run(AsyncBaseLifeCycle.java:370)[121:servicemix-common:2011.01.0]
>   at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown \
> Source)[:1.6.0_33]  at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown \
> Source)[:1.6.0_33]  at java.lang.Thread.run(Unknown Source)[:1.6.0_33]
> [2012-10-09 03:26:26,678] | WARN  | \
> pool-component.servicemix-wsn2005.provider-thread-38 | JmsPublisher             | \
>                 ervicemix.wsn.jms.JmsPublisher   97 | Error dispatching message
> javax.jms.IllegalStateException: The producer is closed
> 	at org.apache.activemq.ActiveMQMessageProducer.checkClosed(ActiveMQMessageProducer.java:169)
>   at org.apache.activemq.ActiveMQMessageProducerSupport.getDeliveryMode(ActiveMQMessageProducerSupport.java:148)
>   at org.apache.activemq.pool.PooledProducer.<init>(PooledProducer.java:44)
> 	at org.apache.activemq.pool.PooledSession.createProducer(PooledSession.java:278)
> 	at org.apache.servicemix.wsn.jms.JmsPublisher.notify(JmsPublisher.java:89)[166:servicemix-wsn2005:2011.01.0]
>   at org.apache.servicemix.wsn.AbstractNotificationBroker.handleNotify(AbstractNotificationBroker.java:134)[166:servicemix-wsn2005:2011.01.0]
>   at org.apache.servicemix.wsn.AbstractNotificationBroker.notify(AbstractNotificationBroker.java:126)[166:servicemix-wsn2005:2011.01.0]
>   at sun.reflect.GeneratedMethodAccessor38.invoke(Unknown Source)
> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)[:1.6.0_33]
> 	at java.lang.reflect.Method.invoke(Unknown Source)[:1.6.0_33]
> 	at org.apache.servicemix.wsn.component.WSNEndpoint.process(WSNEndpoint.java:166)[166:servicemix-wsn2005:2011.01.0]
>   at org.apache.servicemix.common.AsyncBaseLifeCycle.doProcess(AsyncBaseLifeCycle.java:651)[121:servicemix-common:2011.01.0]
>   at org.apache.servicemix.common.AsyncBaseLifeCycle.processExchange(AsyncBaseLifeCycle.java:606)[121:servicemix-common:2011.01.0]
>   at org.apache.servicemix.common.AsyncBaseLifeCycle.processExchangeInTx(AsyncBaseLifeCycle.java:501)[121:servicemix-common:2011.01.0]
>   at org.apache.servicemix.common.AsyncBaseLifeCycle$2.run(AsyncBaseLifeCycle.java:370)[121:servicemix-common:2011.01.0]
>   at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown \
> Source)[:1.6.0_33]  at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown \
> Source)[:1.6.0_33]  at java.lang.Thread.run(Unknown Source)[:1.6.0_33]
> [2012-10-09 03:26:26,788] | WARN  | \
> pool-component.servicemix-wsn2005.provider-thread-39 | JmsPublisher             | \
>                 ervicemix.wsn.jms.JmsPublisher   97 | Error dispatching message
> org.apache.activemq.ConnectionFailedException: The JMS connection has failed: \
> java.io.EOFException  at \
> org.apache.activemq.ActiveMQConnection.checkClosedOrFailed(ActiveMQConnection.java:1354)
>   at org.apache.activemq.ActiveMQConnection.createSession(ActiveMQConnection.java:308)
>   at org.apache.activemq.pool.SessionPool.createSession(SessionPool.java:117)
> 	at org.apache.activemq.pool.SessionPool.makeObject(SessionPool.java:85)
> 	at org.apache.commons.pool.impl.GenericObjectPool.borrowObject(GenericObjectPool.java:1148)
>   at org.apache.activemq.pool.SessionPool.borrowSession(SessionPool.java:54)
> 	at org.apache.activemq.pool.ConnectionPool.createSession(ConnectionPool.java:110)
> 	at org.apache.activemq.pool.PooledConnection.createSession(PooledConnection.java:141)
>   at org.apache.servicemix.wsn.jms.JmsPublisher.notify(JmsPublisher.java:88)[166:servicemix-wsn2005:2011.01.0]
>   at org.apache.servicemix.wsn.AbstractNotificationBroker.handleNotify(AbstractNotificationBroker.java:134)[166:servicemix-wsn2005:2011.01.0]
>   at org.apache.servicemix.wsn.AbstractNotificationBroker.notify(AbstractNotificationBroker.java:126)[166:servicemix-wsn2005:2011.01.0]
>   at sun.reflect.GeneratedMethodAccessor38.invoke(Unknown Source)
> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)[:1.6.0_33]
> 	at java.lang.reflect.Method.invoke(Unknown Source)[:1.6.0_33]
> 	at org.apache.servicemix.wsn.component.WSNEndpoint.process(WSNEndpoint.java:166)[166:servicemix-wsn2005:2011.01.0]
>   at org.apache.servicemix.common.AsyncBaseLifeCycle.doProcess(AsyncBaseLifeCycle.java:651)[121:servicemix-common:2011.01.0]
>   at org.apache.servicemix.common.AsyncBaseLifeCycle.processExchange(AsyncBaseLifeCycle.java:606)[121:servicemix-common:2011.01.0]
>   at org.apache.servicemix.common.AsyncBaseLifeCycle.processExchangeInTx(AsyncBaseLifeCycle.java:501)[121:servicemix-common:2011.01.0]
>   at org.apache.servicemix.common.AsyncBaseLifeCycle$2.run(AsyncBaseLifeCycle.java:370)[121:servicemix-common:2011.01.0]
>   at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown \
> Source)[:1.6.0_33]  at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown \
> Source)[:1.6.0_33]  at java.lang.Thread.run(Unknown Source)[:1.6.0_33]
> Caused by: java.io.EOFException
> 	at java.io.DataInputStream.readInt(Unknown Source)[:1.6.0_33]
> 	at org.apache.activemq.openwire.OpenWireFormat.unmarshal(OpenWireFormat.java:269)[43:org.apache.activemq.activemq-core:5.4.2]
>   at org.apache.activemq.transport.tcp.TcpTransport.readCommand(TcpTransport.java:227)[43:org.apache.activemq.activemq-core:5.4.2]
>   at org.apache.activemq.transport.tcp.TcpTransport.doRun(TcpTransport.java:219)[43:org.apache.activemq.activemq-core:5.4.2]
>   at org.apache.activemq.transport.tcp.TcpTransport.run(TcpTransport.java:202)[43:org.apache.activemq.activemq-core:5.4.2]
>                 
> 	... 1 more

--
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


[prev in list] [next in list] [prev in thread] [next in thread] 

Configure | About | News | Add a list | Sponsored by KoreLogic