Mithun Sunku created AMQ-4105:
---------------------------------

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


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


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

Reply via email to