You are viewing a plain text version of this content. The canonical link for it is here.
Posted to server-dev@james.apache.org by "Tim-Christian Mundt (JIRA)" <se...@james.apache.org> on 2010/04/23 11:33:49 UTC

[jira] Created: (JAMES-991) IllegalStateException during shutdown

IllegalStateException during shutdown
-------------------------------------

                 Key: JAMES-991
                 URL: https://issues.apache.org/jira/browse/JAMES-991
             Project: JAMES Server
          Issue Type: Bug
          Components: James Core
    Affects Versions: Trunk
         Environment: WinXP
            Reporter: Tim-Christian Mundt


Sometimes (I love undetermined errors...) when shutting down James (current rev 936628) the CPU load goes very high and the system (Win XP) becomes totally unresponsive (although James only takes around 50% of the CPU (dual core)). I get this output:

INFO  11:07:21,203 | org.apache.activemq.broker.BrokerService | ActiveMQ Message Broker (localhost, ID:timpad-1542-1272013579921-0:0) is shutting down
INFO  11:07:21,203 | org.apache.james.container.spring.JamesServerApplicationContext | Closing org.apache.james.container.spring.JamesServer
ApplicationContext@12498b5: startup date [Fri Apr 23 11:06:13 CEST 2010]; root of context hierarchy
INFO  11:07:21,218 | james.imapserver | Dispose IMAP Service
INFO  11:07:22,421 | org.apache.activemq.broker.TransportConnector | Connector openwire Stopped
INFO  11:07:22,453 | org.apache.activemq.broker.TransportConnector | Connector vm://localhost Stopped
INFO  11:07:23,921 | org.apache.activemq.broker.BrokerService | ActiveMQ JMS Message Broker (localhost, ID:timpad-1542-1272013579921-0:0) stopped
INFO  11:07:24,781 | org.apache.activemq.broker.BrokerService | Using Persistence Adapter: AMQPersistenceAdapter(activemq-data\localhost)
INFO  11:07:24,796 | org.apache.activemq.store.amq.AMQPersistenceAdapter | AMQStore starting using directory: activemq-data\localhost
INFO  11:07:32,812 | org.apache.activemq.kaha.impl.KahaStore | Kaha Store using data directory activemq-data\localhost\kr-store\state
INFO  11:08:54,515 | org.apache.activemq.store.amq.AMQPersistenceAdapter | Active data files: []

And after a while this error follows:

ERROR 11:09:15,140 | org.apache.activemq.broker.BrokerService | Failed to start ActiveMQ JMS Message Broker. Reason: java.lang.IllegalStateE
xception: Shutdown in progress
java.lang.IllegalStateException: Shutdown in progress
        at java.lang.ApplicationShutdownHooks.add(Unknown Source)
        at java.lang.Runtime.addShutdownHook(Unknown Source)
        at org.apache.activemq.broker.BrokerService.addShutdownHook(BrokerService.java:1844)
        at org.apache.activemq.broker.BrokerService.start(BrokerService.java:468)
        at org.apache.activemq.transport.vm.VMTransportFactory.doCompositeConnect(VMTransportFactory.java:123)
        at org.apache.activemq.transport.vm.VMTransportFactory.doConnect(VMTransportFactory.java:53)
        at org.apache.activemq.transport.TransportFactory.doConnect(TransportFactory.java:51)
        at org.apache.activemq.transport.TransportFactory.connect(TransportFactory.java:80)
        at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:243)
        at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:258)
        at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:230)
        at org.apache.activemq.ActiveMQConnectionFactory.createConnection(ActiveMQConnectionFactory.java:178)
        at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:125)
        at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:110)
        at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:81)
        at org.springframework.jms.support.JmsAccessor.createConnection(JmsAccessor.java:184)
        at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:456)
        at org.springframework.jms.core.JmsTemplate.receiveSelected(JmsTemplate.java:703)
        at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:681)
        at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:672)
        at org.apache.camel.component.jms.JmsPollingConsumer.receive(JmsPollingConsumer.java:71)
        at org.apache.camel.component.jms.JmsPollingConsumer.receiveNoWait(JmsPollingConsumer.java:53)
        at org.apache.james.transport.camel.JMSSelectorPollingConsumer.poll(JMSSelectorPollingConsumer.java:76)
        at org.apache.camel.impl.ScheduledPollConsumer.run(ScheduledPollConsumer.java:106)
        at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source)
        at java.util.concurrent.FutureTask$Sync.innerRunAndReset(Unknown Source)
        at java.util.concurrent.FutureTask.runAndReset(Unknown Source)
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(Unknown Source)
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(Unknown Source)
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source)
        at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
        at java.lang.Thread.run(Unknown Source)
INFO  11:09:19,312 | org.apache.activemq.broker.BrokerService | ActiveMQ Message Broker (localhost, null) is shutting down


I just tried again and after this stacktrace above I get this one:

INFO  11:19:42,328 | org.apache.activemq.broker.BrokerService | ActiveMQ JMS Message Broker (localhost, null) stopped
WARN  11:19:42,343 | org.apache.camel.impl.DefaultPollingConsumerPollStrategy | Consumer Consumer[pollingjms://queue?consumer.endpointUri=activemq%3Aqueue%3Aoutgoing.retry&delay=30000] could not poll endpoint: pollingjms://queue?consumer.endpointUri=activemq%3Aqueue%3Aoutgoing.retry&delay=30000 caused by: Uncategorized exception occured during JMS processing; nested exception is javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
org.springframework.jms.UncategorizedJmsException: Uncategorized exception occured during JMS processing; nested exception is javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
        at org.springframework.jms.support.JmsUtils.convertJmsAccessException(JmsUtils.java:316)
        at org.springframework.jms.support.JmsAccessor.convertJmsAccessException(JmsAccessor.java:168)
        at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:469)
        at org.springframework.jms.core.JmsTemplate.receiveSelected(JmsTemplate.java:703)
        at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:681)
        at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:672)
        at org.apache.camel.component.jms.JmsPollingConsumer.receive(JmsPollingConsumer.java:71)
        at org.apache.camel.component.jms.JmsPollingConsumer.receiveNoWait(JmsPollingConsumer.java:53)
        at org.apache.james.transport.camel.JMSSelectorPollingConsumer.poll(JMSSelectorPollingConsumer.java:76)
        at org.apache.camel.impl.ScheduledPollConsumer.run(ScheduledPollConsumer.java:106)
        at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source)
        at java.util.concurrent.FutureTask$Sync.innerRunAndReset(Unknown Source)
        at java.util.concurrent.FutureTask.runAndReset(Unknown Source)
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(Unknown Source)
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(Unknown Source)
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source)
        at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
        at java.lang.Thread.run(Unknown Source)
Caused by: javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
        at org.apache.activemq.util.JMSExceptionSupport.create(JMSExceptionSupport.java:35)
        at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:245)
        at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:258)
        at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:230)
        at org.apache.activemq.ActiveMQConnectionFactory.createConnection(ActiveMQConnectionFactory.java:178)
        at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:125)
        at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:110)
        at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:81)
        at org.springframework.jms.support.JmsAccessor.createConnection(JmsAccessor.java:184)
        at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:456)
        ... 16 more
Caused by: java.lang.IllegalStateException: Shutdown in progress
        at java.lang.ApplicationShutdownHooks.add(Unknown Source)
        at java.lang.Runtime.addShutdownHook(Unknown Source)
        at org.apache.activemq.broker.BrokerService.addShutdownHook(BrokerService.java:1844)
        at org.apache.activemq.broker.BrokerService.start(BrokerService.java:468)
        at org.apache.activemq.transport.vm.VMTransportFactory.doCompositeConnect(VMTransportFactory.java:123)
        at org.apache.activemq.transport.vm.VMTransportFactory.doConnect(VMTransportFactory.java:53)
        at org.apache.activemq.transport.TransportFactory.doConnect(TransportFactory.java:51)
        at org.apache.activemq.transport.TransportFactory.connect(TransportFactory.java:80)
        at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:243)
        ... 24 more
INFO  11:20:13,312 | org.apache.activemq.broker.BrokerService | Using Persistence Adapter: AMQPersistenceAdapter(activemq-data\localhost)
INFO  11:20:13,312 | org.apache.activemq.store.amq.AMQPersistenceAdapter | AMQStore starting using directory: activemq-data\localhost
INFO  11:20:13,328 | org.apache.activemq.kaha.impl.KahaStore | Kaha Store using data directory activemq-data\localhost\kr-store\state
INFO  11:20:13,359 | org.apache.activemq.store.amq.AMQPersistenceAdapter | Active data files: []


Both these errors alternate with some pause in between until I kill the process. CPU-load is high all the time.


-- 
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.


---------------------------------------------------------------------
To unsubscribe, e-mail: server-dev-unsubscribe@james.apache.org
For additional commands, e-mail: server-dev-help@james.apache.org


[jira] Resolved: (JAMES-991) IllegalStateException during shutdown

Posted by "Norman Maurer (JIRA)" <se...@james.apache.org>.
     [ https://issues.apache.org/jira/browse/JAMES-991?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]

Norman Maurer resolved JAMES-991.
---------------------------------

         Assignee: Norman Maurer
    Fix Version/s: 3.0-M1
       Resolution: Fixed

Use shutdown hook for activemq to fix this.. Thx for reporting

> IllegalStateException during shutdown
> -------------------------------------
>
>                 Key: JAMES-991
>                 URL: https://issues.apache.org/jira/browse/JAMES-991
>             Project: JAMES Server
>          Issue Type: Bug
>          Components: James Core
>    Affects Versions: Trunk
>         Environment: WinXP
>            Reporter: Tim-Christian Mundt
>            Assignee: Norman Maurer
>             Fix For: 3.0-M1
>
>
> Sometimes (I love undetermined errors...) when shutting down James (current rev 936628) the CPU load goes very high and the system (Win XP) becomes totally unresponsive (although James only takes around 50% of the CPU (dual core)). I get this output:
> INFO  11:07:21,203 | org.apache.activemq.broker.BrokerService | ActiveMQ Message Broker (localhost, ID:timpad-1542-1272013579921-0:0) is shutting down
> INFO  11:07:21,203 | org.apache.james.container.spring.JamesServerApplicationContext | Closing org.apache.james.container.spring.JamesServer
> ApplicationContext@12498b5: startup date [Fri Apr 23 11:06:13 CEST 2010]; root of context hierarchy
> INFO  11:07:21,218 | james.imapserver | Dispose IMAP Service
> INFO  11:07:22,421 | org.apache.activemq.broker.TransportConnector | Connector openwire Stopped
> INFO  11:07:22,453 | org.apache.activemq.broker.TransportConnector | Connector vm://localhost Stopped
> INFO  11:07:23,921 | org.apache.activemq.broker.BrokerService | ActiveMQ JMS Message Broker (localhost, ID:timpad-1542-1272013579921-0:0) stopped
> INFO  11:07:24,781 | org.apache.activemq.broker.BrokerService | Using Persistence Adapter: AMQPersistenceAdapter(activemq-data\localhost)
> INFO  11:07:24,796 | org.apache.activemq.store.amq.AMQPersistenceAdapter | AMQStore starting using directory: activemq-data\localhost
> INFO  11:07:32,812 | org.apache.activemq.kaha.impl.KahaStore | Kaha Store using data directory activemq-data\localhost\kr-store\state
> INFO  11:08:54,515 | org.apache.activemq.store.amq.AMQPersistenceAdapter | Active data files: []
> And after a while this error follows:
> ERROR 11:09:15,140 | org.apache.activemq.broker.BrokerService | Failed to start ActiveMQ JMS Message Broker. Reason: java.lang.IllegalStateE
> xception: Shutdown in progress
> java.lang.IllegalStateException: Shutdown in progress
>         at java.lang.ApplicationShutdownHooks.add(Unknown Source)
>         at java.lang.Runtime.addShutdownHook(Unknown Source)
>         at org.apache.activemq.broker.BrokerService.addShutdownHook(BrokerService.java:1844)
>         at org.apache.activemq.broker.BrokerService.start(BrokerService.java:468)
>         at org.apache.activemq.transport.vm.VMTransportFactory.doCompositeConnect(VMTransportFactory.java:123)
>         at org.apache.activemq.transport.vm.VMTransportFactory.doConnect(VMTransportFactory.java:53)
>         at org.apache.activemq.transport.TransportFactory.doConnect(TransportFactory.java:51)
>         at org.apache.activemq.transport.TransportFactory.connect(TransportFactory.java:80)
>         at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:243)
>         at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:258)
>         at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:230)
>         at org.apache.activemq.ActiveMQConnectionFactory.createConnection(ActiveMQConnectionFactory.java:178)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:125)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:110)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:81)
>         at org.springframework.jms.support.JmsAccessor.createConnection(JmsAccessor.java:184)
>         at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:456)
>         at org.springframework.jms.core.JmsTemplate.receiveSelected(JmsTemplate.java:703)
>         at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:681)
>         at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:672)
>         at org.apache.camel.component.jms.JmsPollingConsumer.receive(JmsPollingConsumer.java:71)
>         at org.apache.camel.component.jms.JmsPollingConsumer.receiveNoWait(JmsPollingConsumer.java:53)
>         at org.apache.james.transport.camel.JMSSelectorPollingConsumer.poll(JMSSelectorPollingConsumer.java:76)
>         at org.apache.camel.impl.ScheduledPollConsumer.run(ScheduledPollConsumer.java:106)
>         at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source)
>         at java.util.concurrent.FutureTask$Sync.innerRunAndReset(Unknown Source)
>         at java.util.concurrent.FutureTask.runAndReset(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
>         at java.lang.Thread.run(Unknown Source)
> INFO  11:09:19,312 | org.apache.activemq.broker.BrokerService | ActiveMQ Message Broker (localhost, null) is shutting down
> I just tried again and after this stacktrace above I get this one:
> INFO  11:19:42,328 | org.apache.activemq.broker.BrokerService | ActiveMQ JMS Message Broker (localhost, null) stopped
> WARN  11:19:42,343 | org.apache.camel.impl.DefaultPollingConsumerPollStrategy | Consumer Consumer[pollingjms://queue?consumer.endpointUri=activemq%3Aqueue%3Aoutgoing.retry&delay=30000] could not poll endpoint: pollingjms://queue?consumer.endpointUri=activemq%3Aqueue%3Aoutgoing.retry&delay=30000 caused by: Uncategorized exception occured during JMS processing; nested exception is javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
> org.springframework.jms.UncategorizedJmsException: Uncategorized exception occured during JMS processing; nested exception is javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
>         at org.springframework.jms.support.JmsUtils.convertJmsAccessException(JmsUtils.java:316)
>         at org.springframework.jms.support.JmsAccessor.convertJmsAccessException(JmsAccessor.java:168)
>         at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:469)
>         at org.springframework.jms.core.JmsTemplate.receiveSelected(JmsTemplate.java:703)
>         at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:681)
>         at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:672)
>         at org.apache.camel.component.jms.JmsPollingConsumer.receive(JmsPollingConsumer.java:71)
>         at org.apache.camel.component.jms.JmsPollingConsumer.receiveNoWait(JmsPollingConsumer.java:53)
>         at org.apache.james.transport.camel.JMSSelectorPollingConsumer.poll(JMSSelectorPollingConsumer.java:76)
>         at org.apache.camel.impl.ScheduledPollConsumer.run(ScheduledPollConsumer.java:106)
>         at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source)
>         at java.util.concurrent.FutureTask$Sync.innerRunAndReset(Unknown Source)
>         at java.util.concurrent.FutureTask.runAndReset(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
>         at java.lang.Thread.run(Unknown Source)
> Caused by: javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
>         at org.apache.activemq.util.JMSExceptionSupport.create(JMSExceptionSupport.java:35)
>         at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:245)
>         at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:258)
>         at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:230)
>         at org.apache.activemq.ActiveMQConnectionFactory.createConnection(ActiveMQConnectionFactory.java:178)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:125)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:110)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:81)
>         at org.springframework.jms.support.JmsAccessor.createConnection(JmsAccessor.java:184)
>         at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:456)
>         ... 16 more
> Caused by: java.lang.IllegalStateException: Shutdown in progress
>         at java.lang.ApplicationShutdownHooks.add(Unknown Source)
>         at java.lang.Runtime.addShutdownHook(Unknown Source)
>         at org.apache.activemq.broker.BrokerService.addShutdownHook(BrokerService.java:1844)
>         at org.apache.activemq.broker.BrokerService.start(BrokerService.java:468)
>         at org.apache.activemq.transport.vm.VMTransportFactory.doCompositeConnect(VMTransportFactory.java:123)
>         at org.apache.activemq.transport.vm.VMTransportFactory.doConnect(VMTransportFactory.java:53)
>         at org.apache.activemq.transport.TransportFactory.doConnect(TransportFactory.java:51)
>         at org.apache.activemq.transport.TransportFactory.connect(TransportFactory.java:80)
>         at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:243)
>         ... 24 more
> INFO  11:20:13,312 | org.apache.activemq.broker.BrokerService | Using Persistence Adapter: AMQPersistenceAdapter(activemq-data\localhost)
> INFO  11:20:13,312 | org.apache.activemq.store.amq.AMQPersistenceAdapter | AMQStore starting using directory: activemq-data\localhost
> INFO  11:20:13,328 | org.apache.activemq.kaha.impl.KahaStore | Kaha Store using data directory activemq-data\localhost\kr-store\state
> INFO  11:20:13,359 | org.apache.activemq.store.amq.AMQPersistenceAdapter | Active data files: []
> Both these errors alternate with some pause in between until I kill the process. CPU-load is high all the time.

-- 
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.


---------------------------------------------------------------------
To unsubscribe, e-mail: server-dev-unsubscribe@james.apache.org
For additional commands, e-mail: server-dev-help@james.apache.org


[jira] Commented: (JAMES-991) IllegalStateException during shutdown

Posted by "Norman Maurer (JIRA)" <se...@james.apache.org>.
    [ https://issues.apache.org/jira/browse/JAMES-991?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=12877798#action_12877798 ] 

Norman Maurer commented on JAMES-991:
-------------------------------------

The high load was a side effect of not closing open sockets while shudown tcp servers, see https://issues.apache.org/jira/browse/JAMES-1014.

About the IllegealStateException I will have a look again. 

> IllegalStateException during shutdown
> -------------------------------------
>
>                 Key: JAMES-991
>                 URL: https://issues.apache.org/jira/browse/JAMES-991
>             Project: JAMES Server
>          Issue Type: Bug
>          Components: James Core
>    Affects Versions: Trunk
>         Environment: WinXP
>            Reporter: Tim-Christian Mundt
>            Assignee: Norman Maurer
>             Fix For: 3.0-M1
>
>
> Sometimes (I love undetermined errors...) when shutting down James (current rev 936628) the CPU load goes very high and the system (Win XP) becomes totally unresponsive (although James only takes around 50% of the CPU (dual core)). I get this output:
> INFO  11:07:21,203 | org.apache.activemq.broker.BrokerService | ActiveMQ Message Broker (localhost, ID:timpad-1542-1272013579921-0:0) is shutting down
> INFO  11:07:21,203 | org.apache.james.container.spring.JamesServerApplicationContext | Closing org.apache.james.container.spring.JamesServer
> ApplicationContext@12498b5: startup date [Fri Apr 23 11:06:13 CEST 2010]; root of context hierarchy
> INFO  11:07:21,218 | james.imapserver | Dispose IMAP Service
> INFO  11:07:22,421 | org.apache.activemq.broker.TransportConnector | Connector openwire Stopped
> INFO  11:07:22,453 | org.apache.activemq.broker.TransportConnector | Connector vm://localhost Stopped
> INFO  11:07:23,921 | org.apache.activemq.broker.BrokerService | ActiveMQ JMS Message Broker (localhost, ID:timpad-1542-1272013579921-0:0) stopped
> INFO  11:07:24,781 | org.apache.activemq.broker.BrokerService | Using Persistence Adapter: AMQPersistenceAdapter(activemq-data\localhost)
> INFO  11:07:24,796 | org.apache.activemq.store.amq.AMQPersistenceAdapter | AMQStore starting using directory: activemq-data\localhost
> INFO  11:07:32,812 | org.apache.activemq.kaha.impl.KahaStore | Kaha Store using data directory activemq-data\localhost\kr-store\state
> INFO  11:08:54,515 | org.apache.activemq.store.amq.AMQPersistenceAdapter | Active data files: []
> And after a while this error follows:
> ERROR 11:09:15,140 | org.apache.activemq.broker.BrokerService | Failed to start ActiveMQ JMS Message Broker. Reason: java.lang.IllegalStateE
> xception: Shutdown in progress
> java.lang.IllegalStateException: Shutdown in progress
>         at java.lang.ApplicationShutdownHooks.add(Unknown Source)
>         at java.lang.Runtime.addShutdownHook(Unknown Source)
>         at org.apache.activemq.broker.BrokerService.addShutdownHook(BrokerService.java:1844)
>         at org.apache.activemq.broker.BrokerService.start(BrokerService.java:468)
>         at org.apache.activemq.transport.vm.VMTransportFactory.doCompositeConnect(VMTransportFactory.java:123)
>         at org.apache.activemq.transport.vm.VMTransportFactory.doConnect(VMTransportFactory.java:53)
>         at org.apache.activemq.transport.TransportFactory.doConnect(TransportFactory.java:51)
>         at org.apache.activemq.transport.TransportFactory.connect(TransportFactory.java:80)
>         at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:243)
>         at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:258)
>         at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:230)
>         at org.apache.activemq.ActiveMQConnectionFactory.createConnection(ActiveMQConnectionFactory.java:178)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:125)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:110)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:81)
>         at org.springframework.jms.support.JmsAccessor.createConnection(JmsAccessor.java:184)
>         at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:456)
>         at org.springframework.jms.core.JmsTemplate.receiveSelected(JmsTemplate.java:703)
>         at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:681)
>         at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:672)
>         at org.apache.camel.component.jms.JmsPollingConsumer.receive(JmsPollingConsumer.java:71)
>         at org.apache.camel.component.jms.JmsPollingConsumer.receiveNoWait(JmsPollingConsumer.java:53)
>         at org.apache.james.transport.camel.JMSSelectorPollingConsumer.poll(JMSSelectorPollingConsumer.java:76)
>         at org.apache.camel.impl.ScheduledPollConsumer.run(ScheduledPollConsumer.java:106)
>         at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source)
>         at java.util.concurrent.FutureTask$Sync.innerRunAndReset(Unknown Source)
>         at java.util.concurrent.FutureTask.runAndReset(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
>         at java.lang.Thread.run(Unknown Source)
> INFO  11:09:19,312 | org.apache.activemq.broker.BrokerService | ActiveMQ Message Broker (localhost, null) is shutting down
> I just tried again and after this stacktrace above I get this one:
> INFO  11:19:42,328 | org.apache.activemq.broker.BrokerService | ActiveMQ JMS Message Broker (localhost, null) stopped
> WARN  11:19:42,343 | org.apache.camel.impl.DefaultPollingConsumerPollStrategy | Consumer Consumer[pollingjms://queue?consumer.endpointUri=activemq%3Aqueue%3Aoutgoing.retry&delay=30000] could not poll endpoint: pollingjms://queue?consumer.endpointUri=activemq%3Aqueue%3Aoutgoing.retry&delay=30000 caused by: Uncategorized exception occured during JMS processing; nested exception is javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
> org.springframework.jms.UncategorizedJmsException: Uncategorized exception occured during JMS processing; nested exception is javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
>         at org.springframework.jms.support.JmsUtils.convertJmsAccessException(JmsUtils.java:316)
>         at org.springframework.jms.support.JmsAccessor.convertJmsAccessException(JmsAccessor.java:168)
>         at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:469)
>         at org.springframework.jms.core.JmsTemplate.receiveSelected(JmsTemplate.java:703)
>         at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:681)
>         at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:672)
>         at org.apache.camel.component.jms.JmsPollingConsumer.receive(JmsPollingConsumer.java:71)
>         at org.apache.camel.component.jms.JmsPollingConsumer.receiveNoWait(JmsPollingConsumer.java:53)
>         at org.apache.james.transport.camel.JMSSelectorPollingConsumer.poll(JMSSelectorPollingConsumer.java:76)
>         at org.apache.camel.impl.ScheduledPollConsumer.run(ScheduledPollConsumer.java:106)
>         at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source)
>         at java.util.concurrent.FutureTask$Sync.innerRunAndReset(Unknown Source)
>         at java.util.concurrent.FutureTask.runAndReset(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
>         at java.lang.Thread.run(Unknown Source)
> Caused by: javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
>         at org.apache.activemq.util.JMSExceptionSupport.create(JMSExceptionSupport.java:35)
>         at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:245)
>         at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:258)
>         at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:230)
>         at org.apache.activemq.ActiveMQConnectionFactory.createConnection(ActiveMQConnectionFactory.java:178)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:125)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:110)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:81)
>         at org.springframework.jms.support.JmsAccessor.createConnection(JmsAccessor.java:184)
>         at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:456)
>         ... 16 more
> Caused by: java.lang.IllegalStateException: Shutdown in progress
>         at java.lang.ApplicationShutdownHooks.add(Unknown Source)
>         at java.lang.Runtime.addShutdownHook(Unknown Source)
>         at org.apache.activemq.broker.BrokerService.addShutdownHook(BrokerService.java:1844)
>         at org.apache.activemq.broker.BrokerService.start(BrokerService.java:468)
>         at org.apache.activemq.transport.vm.VMTransportFactory.doCompositeConnect(VMTransportFactory.java:123)
>         at org.apache.activemq.transport.vm.VMTransportFactory.doConnect(VMTransportFactory.java:53)
>         at org.apache.activemq.transport.TransportFactory.doConnect(TransportFactory.java:51)
>         at org.apache.activemq.transport.TransportFactory.connect(TransportFactory.java:80)
>         at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:243)
>         ... 24 more
> INFO  11:20:13,312 | org.apache.activemq.broker.BrokerService | Using Persistence Adapter: AMQPersistenceAdapter(activemq-data\localhost)
> INFO  11:20:13,312 | org.apache.activemq.store.amq.AMQPersistenceAdapter | AMQStore starting using directory: activemq-data\localhost
> INFO  11:20:13,328 | org.apache.activemq.kaha.impl.KahaStore | Kaha Store using data directory activemq-data\localhost\kr-store\state
> INFO  11:20:13,359 | org.apache.activemq.store.amq.AMQPersistenceAdapter | Active data files: []
> Both these errors alternate with some pause in between until I kill the process. CPU-load is high all the time.

-- 
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.


---------------------------------------------------------------------
To unsubscribe, e-mail: server-dev-unsubscribe@james.apache.org
For additional commands, e-mail: server-dev-help@james.apache.org


[jira] Reopened: (JAMES-991) IllegalStateException during shutdown

Posted by "Tim-Christian Mundt (JIRA)" <se...@james.apache.org>.
     [ https://issues.apache.org/jira/browse/JAMES-991?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]

Tim-Christian Mundt reopened JAMES-991:
---------------------------------------


It's occurring again. Maybe a more generous stack trace can help diagnosing :)

ERROR 13:32:58,955 | org.apache.activemq.broker.BrokerService | Failed to start ActiveMQ JMS Message Broker. Reason: java.lang.IllegalStateException: Shutdown in progress
java.lang.IllegalStateException: Shutdown in progress
	at java.lang.ApplicationShutdownHooks.add(ApplicationShutdownHooks.java:39)
	at java.lang.Runtime.addShutdownHook(Runtime.java:192)
	at org.apache.activemq.broker.BrokerService.addShutdownHook(BrokerService.java:1844)
	at org.apache.activemq.broker.BrokerService.start(BrokerService.java:468)
	at org.apache.activemq.transport.vm.VMTransportFactory.doCompositeConnect(VMTransportFactory.java:123)
	at org.apache.activemq.transport.vm.VMTransportFactory.doConnect(VMTransportFactory.java:53)
	at org.apache.activemq.transport.TransportFactory.doConnect(TransportFactory.java:51)
	at org.apache.activemq.transport.TransportFactory.connect(TransportFactory.java:80)
	at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:243)
	at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:258)
	at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:230)
	at org.apache.activemq.ActiveMQConnectionFactory.createConnection(ActiveMQConnectionFactory.java:178)
	at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:125)
	at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:110)
	at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:81)
	at org.springframework.jms.support.JmsAccessor.createConnection(JmsAccessor.java:184)
	at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:456)
	at org.springframework.jms.core.JmsTemplate.receiveSelected(JmsTemplate.java:703)
	at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:681)
	at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:672)
	at org.apache.camel.component.jms.JmsPollingConsumer.receive(JmsPollingConsumer.java:71)
	at org.apache.camel.component.jms.JmsPollingConsumer.receiveNoWait(JmsPollingConsumer.java:53)
	at org.apache.james.transport.camel.JMSSelectorPollingConsumer.poll(JMSSelectorPollingConsumer.java:76)
	at org.apache.camel.impl.ScheduledPollConsumer.run(ScheduledPollConsumer.java:106)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
	at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:317)
	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:150)
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:98)
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(ScheduledThreadPoolExecutor.java:181)
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:205)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
	at java.lang.Thread.run(Thread.java:619)
INFO  13:32:58,956 | org.apache.activemq.broker.BrokerService | ActiveMQ Message Broker (localhost, null) is shutting down
INFO  13:32:59,110 | org.apache.activemq.broker.BrokerService | ActiveMQ JMS Message Broker (localhost, null) stopped
WARN  13:32:59,113 | org.apache.camel.impl.DefaultPollingConsumerPollStrategy | Consumer Consumer[pollingjms://queue?consumer.endpointUri=activemq%3Aqueue%3Aoutgoing.retry&delay=30000] could not poll endpoint: pollingjms://queue?consumer.endpointUri=activemq%3Aqueue%3Aoutgoing.retry&delay=30000 caused by: Uncategorized exception occured during JMS processing; nested exception is javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
org.springframework.jms.UncategorizedJmsException: Uncategorized exception occured during JMS processing; nested exception is javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
	at org.springframework.jms.support.JmsUtils.convertJmsAccessException(JmsUtils.java:316)
	at org.springframework.jms.support.JmsAccessor.convertJmsAccessException(JmsAccessor.java:168)
	at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:469)
	at org.springframework.jms.core.JmsTemplate.receiveSelected(JmsTemplate.java:703)
	at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:681)
	at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:672)
	at org.apache.camel.component.jms.JmsPollingConsumer.receive(JmsPollingConsumer.java:71)
	at org.apache.camel.component.jms.JmsPollingConsumer.receiveNoWait(JmsPollingConsumer.java:53)
	at org.apache.james.transport.camel.JMSSelectorPollingConsumer.poll(JMSSelectorPollingConsumer.java:76)
	at org.apache.camel.impl.ScheduledPollConsumer.run(ScheduledPollConsumer.java:106)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
	at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:317)
	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:150)
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:98)
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(ScheduledThreadPoolExecutor.java:181)
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:205)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
	at java.lang.Thread.run(Thread.java:619)
Caused by: javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
	at org.apache.activemq.util.JMSExceptionSupport.create(JMSExceptionSupport.java:35)
	at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:245)
	at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:258)
	at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:230)
	at org.apache.activemq.ActiveMQConnectionFactory.createConnection(ActiveMQConnectionFactory.java:178)
	at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:125)
	at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:110)
	at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:81)
	at org.springframework.jms.support.JmsAccessor.createConnection(JmsAccessor.java:184)
	at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:456)
	... 16 more
Caused by: java.lang.IllegalStateException: Shutdown in progress
	at java.lang.ApplicationShutdownHooks.add(ApplicationShutdownHooks.java:39)
	at java.lang.Runtime.addShutdownHook(Runtime.java:192)
	at org.apache.activemq.broker.BrokerService.addShutdownHook(BrokerService.java:1844)
	at org.apache.activemq.broker.BrokerService.start(BrokerService.java:468)
	at org.apache.activemq.transport.vm.VMTransportFactory.doCompositeConnect(VMTransportFactory.java:123)
	at org.apache.activemq.transport.vm.VMTransportFactory.doConnect(VMTransportFactory.java:53)
	at org.apache.activemq.transport.TransportFactory.doConnect(TransportFactory.java:51)
	at org.apache.activemq.transport.TransportFactory.connect(TransportFactory.java:80)
	at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:243)
	... 24 more
INFO  13:33:28,688 | org.apache.activemq.broker.BrokerService | Using Persistence Adapter: AMQPersistenceAdapter(activemq-data/localhost)
INFO  13:33:28,689 | org.apache.activemq.store.amq.AMQPersistenceAdapter | AMQStore starting using directory: activemq-data/localhost
INFO  13:33:28,737 | org.apache.activemq.kaha.impl.KahaStore | Kaha Store using data directory activemq-data/localhost/kr-store/state
INFO  13:33:28,742 | org.apache.activemq.store.amq.AMQPersistenceAdapter | Active data files: []
ERROR 13:33:28,846 | org.apache.activemq.broker.BrokerService | Failed to start ActiveMQ JMS Message Broker. Reason: java.lang.IllegalStateException: Shutdown in progress
java.lang.IllegalStateException: Shutdown in progress
	at java.lang.ApplicationShutdownHooks.add(ApplicationShutdownHooks.java:39)
	at java.lang.Runtime.addShutdownHook(Runtime.java:192)
	at org.apache.activemq.broker.BrokerService.addShutdownHook(BrokerService.java:1844)
	at org.apache.activemq.broker.BrokerService.start(BrokerService.java:468)
	at org.apache.activemq.transport.vm.VMTransportFactory.doCompositeConnect(VMTransportFactory.java:123)
	at org.apache.activemq.transport.vm.VMTransportFactory.doConnect(VMTransportFactory.java:53)
	at org.apache.activemq.transport.TransportFactory.doConnect(TransportFactory.java:51)
	at org.apache.activemq.transport.TransportFactory.connect(TransportFactory.java:80)
	at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:243)
	at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:258)
	at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:230)
	at org.apache.activemq.ActiveMQConnectionFactory.createConnection(ActiveMQConnectionFactory.java:178)
	at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:125)
	at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:110)
	at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:81)
	at org.springframework.jms.support.JmsAccessor.createConnection(JmsAccessor.java:184)
	at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:456)
	at org.springframework.jms.core.JmsTemplate.receiveSelected(JmsTemplate.java:703)
	at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:681)
	at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:672)
	at org.apache.camel.component.jms.JmsPollingConsumer.receive(JmsPollingConsumer.java:71)
	at org.apache.camel.component.jms.JmsPollingConsumer.receiveNoWait(JmsPollingConsumer.java:53)
	at org.apache.james.transport.camel.JMSSelectorPollingConsumer.poll(JMSSelectorPollingConsumer.java:76)
	at org.apache.camel.impl.ScheduledPollConsumer.run(ScheduledPollConsumer.java:106)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
	at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:317)
	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:150)
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:98)
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(ScheduledThreadPoolExecutor.java:181)
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:205)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
	at java.lang.Thread.run(Thread.java:619)
INFO  13:33:28,847 | org.apache.activemq.broker.BrokerService | ActiveMQ Message Broker (localhost, null) is shutting down
INFO  13:33:28,958 | org.apache.activemq.broker.BrokerService | ActiveMQ JMS Message Broker (localhost, null) stopped
WARN  13:33:28,959 | org.apache.camel.impl.DefaultPollingConsumerPollStrategy | Consumer Consumer[pollingjms://queue?consumer.endpointUri=activemq%3Aqueue%3Aoutgoing.retry&delay=30000] could not poll endpoint: pollingjms://queue?consumer.endpointUri=activemq%3Aqueue%3Aoutgoing.retry&delay=30000 caused by: Uncategorized exception occured during JMS processing; nested exception is javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
org.springframework.jms.UncategorizedJmsException: Uncategorized exception occured during JMS processing; nested exception is javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
	at org.springframework.jms.support.JmsUtils.convertJmsAccessException(JmsUtils.java:316)
	at org.springframework.jms.support.JmsAccessor.convertJmsAccessException(JmsAccessor.java:168)
	at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:469)
	at org.springframework.jms.core.JmsTemplate.receiveSelected(JmsTemplate.java:703)
	at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:681)
	at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:672)
	at org.apache.camel.component.jms.JmsPollingConsumer.receive(JmsPollingConsumer.java:71)
	at org.apache.camel.component.jms.JmsPollingConsumer.receiveNoWait(JmsPollingConsumer.java:53)
	at org.apache.james.transport.camel.JMSSelectorPollingConsumer.poll(JMSSelectorPollingConsumer.java:76)
	at org.apache.camel.impl.ScheduledPollConsumer.run(ScheduledPollConsumer.java:106)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
	at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:317)
	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:150)
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:98)
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(ScheduledThreadPoolExecutor.java:181)
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:205)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
	at java.lang.Thread.run(Thread.java:619)
Caused by: javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
	at org.apache.activemq.util.JMSExceptionSupport.create(JMSExceptionSupport.java:35)
	at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:245)
	at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:258)
	at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:230)
	at org.apache.activemq.ActiveMQConnectionFactory.createConnection(ActiveMQConnectionFactory.java:178)
	at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:125)
	at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:110)
	at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:81)
	at org.springframework.jms.support.JmsAccessor.createConnection(JmsAccessor.java:184)
	at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:456)
	... 16 more
Caused by: java.lang.IllegalStateException: Shutdown in progress
	at java.lang.ApplicationShutdownHooks.add(ApplicationShutdownHooks.java:39)
	at java.lang.Runtime.addShutdownHook(Runtime.java:192)
	at org.apache.activemq.broker.BrokerService.addShutdownHook(BrokerService.java:1844)
	at org.apache.activemq.broker.BrokerService.start(BrokerService.java:468)
	at org.apache.activemq.transport.vm.VMTransportFactory.doCompositeConnect(VMTransportFactory.java:123)
	at org.apache.activemq.transport.vm.VMTransportFactory.doConnect(VMTransportFactory.java:53)
	at org.apache.activemq.transport.TransportFactory.doConnect(TransportFactory.java:51)
	at org.apache.activemq.transport.TransportFactory.connect(TransportFactory.java:80)
	at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:243)
	... 24 more




> IllegalStateException during shutdown
> -------------------------------------
>
>                 Key: JAMES-991
>                 URL: https://issues.apache.org/jira/browse/JAMES-991
>             Project: JAMES Server
>          Issue Type: Bug
>          Components: James Core
>    Affects Versions: Trunk
>         Environment: WinXP
>            Reporter: Tim-Christian Mundt
>            Assignee: Norman Maurer
>             Fix For: 3.0-M1
>
>
> Sometimes (I love undetermined errors...) when shutting down James (current rev 936628) the CPU load goes very high and the system (Win XP) becomes totally unresponsive (although James only takes around 50% of the CPU (dual core)). I get this output:
> INFO  11:07:21,203 | org.apache.activemq.broker.BrokerService | ActiveMQ Message Broker (localhost, ID:timpad-1542-1272013579921-0:0) is shutting down
> INFO  11:07:21,203 | org.apache.james.container.spring.JamesServerApplicationContext | Closing org.apache.james.container.spring.JamesServer
> ApplicationContext@12498b5: startup date [Fri Apr 23 11:06:13 CEST 2010]; root of context hierarchy
> INFO  11:07:21,218 | james.imapserver | Dispose IMAP Service
> INFO  11:07:22,421 | org.apache.activemq.broker.TransportConnector | Connector openwire Stopped
> INFO  11:07:22,453 | org.apache.activemq.broker.TransportConnector | Connector vm://localhost Stopped
> INFO  11:07:23,921 | org.apache.activemq.broker.BrokerService | ActiveMQ JMS Message Broker (localhost, ID:timpad-1542-1272013579921-0:0) stopped
> INFO  11:07:24,781 | org.apache.activemq.broker.BrokerService | Using Persistence Adapter: AMQPersistenceAdapter(activemq-data\localhost)
> INFO  11:07:24,796 | org.apache.activemq.store.amq.AMQPersistenceAdapter | AMQStore starting using directory: activemq-data\localhost
> INFO  11:07:32,812 | org.apache.activemq.kaha.impl.KahaStore | Kaha Store using data directory activemq-data\localhost\kr-store\state
> INFO  11:08:54,515 | org.apache.activemq.store.amq.AMQPersistenceAdapter | Active data files: []
> And after a while this error follows:
> ERROR 11:09:15,140 | org.apache.activemq.broker.BrokerService | Failed to start ActiveMQ JMS Message Broker. Reason: java.lang.IllegalStateE
> xception: Shutdown in progress
> java.lang.IllegalStateException: Shutdown in progress
>         at java.lang.ApplicationShutdownHooks.add(Unknown Source)
>         at java.lang.Runtime.addShutdownHook(Unknown Source)
>         at org.apache.activemq.broker.BrokerService.addShutdownHook(BrokerService.java:1844)
>         at org.apache.activemq.broker.BrokerService.start(BrokerService.java:468)
>         at org.apache.activemq.transport.vm.VMTransportFactory.doCompositeConnect(VMTransportFactory.java:123)
>         at org.apache.activemq.transport.vm.VMTransportFactory.doConnect(VMTransportFactory.java:53)
>         at org.apache.activemq.transport.TransportFactory.doConnect(TransportFactory.java:51)
>         at org.apache.activemq.transport.TransportFactory.connect(TransportFactory.java:80)
>         at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:243)
>         at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:258)
>         at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:230)
>         at org.apache.activemq.ActiveMQConnectionFactory.createConnection(ActiveMQConnectionFactory.java:178)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:125)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:110)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:81)
>         at org.springframework.jms.support.JmsAccessor.createConnection(JmsAccessor.java:184)
>         at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:456)
>         at org.springframework.jms.core.JmsTemplate.receiveSelected(JmsTemplate.java:703)
>         at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:681)
>         at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:672)
>         at org.apache.camel.component.jms.JmsPollingConsumer.receive(JmsPollingConsumer.java:71)
>         at org.apache.camel.component.jms.JmsPollingConsumer.receiveNoWait(JmsPollingConsumer.java:53)
>         at org.apache.james.transport.camel.JMSSelectorPollingConsumer.poll(JMSSelectorPollingConsumer.java:76)
>         at org.apache.camel.impl.ScheduledPollConsumer.run(ScheduledPollConsumer.java:106)
>         at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source)
>         at java.util.concurrent.FutureTask$Sync.innerRunAndReset(Unknown Source)
>         at java.util.concurrent.FutureTask.runAndReset(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
>         at java.lang.Thread.run(Unknown Source)
> INFO  11:09:19,312 | org.apache.activemq.broker.BrokerService | ActiveMQ Message Broker (localhost, null) is shutting down
> I just tried again and after this stacktrace above I get this one:
> INFO  11:19:42,328 | org.apache.activemq.broker.BrokerService | ActiveMQ JMS Message Broker (localhost, null) stopped
> WARN  11:19:42,343 | org.apache.camel.impl.DefaultPollingConsumerPollStrategy | Consumer Consumer[pollingjms://queue?consumer.endpointUri=activemq%3Aqueue%3Aoutgoing.retry&delay=30000] could not poll endpoint: pollingjms://queue?consumer.endpointUri=activemq%3Aqueue%3Aoutgoing.retry&delay=30000 caused by: Uncategorized exception occured during JMS processing; nested exception is javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
> org.springframework.jms.UncategorizedJmsException: Uncategorized exception occured during JMS processing; nested exception is javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
>         at org.springframework.jms.support.JmsUtils.convertJmsAccessException(JmsUtils.java:316)
>         at org.springframework.jms.support.JmsAccessor.convertJmsAccessException(JmsAccessor.java:168)
>         at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:469)
>         at org.springframework.jms.core.JmsTemplate.receiveSelected(JmsTemplate.java:703)
>         at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:681)
>         at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:672)
>         at org.apache.camel.component.jms.JmsPollingConsumer.receive(JmsPollingConsumer.java:71)
>         at org.apache.camel.component.jms.JmsPollingConsumer.receiveNoWait(JmsPollingConsumer.java:53)
>         at org.apache.james.transport.camel.JMSSelectorPollingConsumer.poll(JMSSelectorPollingConsumer.java:76)
>         at org.apache.camel.impl.ScheduledPollConsumer.run(ScheduledPollConsumer.java:106)
>         at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source)
>         at java.util.concurrent.FutureTask$Sync.innerRunAndReset(Unknown Source)
>         at java.util.concurrent.FutureTask.runAndReset(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
>         at java.lang.Thread.run(Unknown Source)
> Caused by: javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
>         at org.apache.activemq.util.JMSExceptionSupport.create(JMSExceptionSupport.java:35)
>         at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:245)
>         at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:258)
>         at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:230)
>         at org.apache.activemq.ActiveMQConnectionFactory.createConnection(ActiveMQConnectionFactory.java:178)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:125)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:110)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:81)
>         at org.springframework.jms.support.JmsAccessor.createConnection(JmsAccessor.java:184)
>         at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:456)
>         ... 16 more
> Caused by: java.lang.IllegalStateException: Shutdown in progress
>         at java.lang.ApplicationShutdownHooks.add(Unknown Source)
>         at java.lang.Runtime.addShutdownHook(Unknown Source)
>         at org.apache.activemq.broker.BrokerService.addShutdownHook(BrokerService.java:1844)
>         at org.apache.activemq.broker.BrokerService.start(BrokerService.java:468)
>         at org.apache.activemq.transport.vm.VMTransportFactory.doCompositeConnect(VMTransportFactory.java:123)
>         at org.apache.activemq.transport.vm.VMTransportFactory.doConnect(VMTransportFactory.java:53)
>         at org.apache.activemq.transport.TransportFactory.doConnect(TransportFactory.java:51)
>         at org.apache.activemq.transport.TransportFactory.connect(TransportFactory.java:80)
>         at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:243)
>         ... 24 more
> INFO  11:20:13,312 | org.apache.activemq.broker.BrokerService | Using Persistence Adapter: AMQPersistenceAdapter(activemq-data\localhost)
> INFO  11:20:13,312 | org.apache.activemq.store.amq.AMQPersistenceAdapter | AMQStore starting using directory: activemq-data\localhost
> INFO  11:20:13,328 | org.apache.activemq.kaha.impl.KahaStore | Kaha Store using data directory activemq-data\localhost\kr-store\state
> INFO  11:20:13,359 | org.apache.activemq.store.amq.AMQPersistenceAdapter | Active data files: []
> Both these errors alternate with some pause in between until I kill the process. CPU-load is high all the time.

-- 
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.


---------------------------------------------------------------------
To unsubscribe, e-mail: server-dev-unsubscribe@james.apache.org
For additional commands, e-mail: server-dev-help@james.apache.org


[jira] Commented: (JAMES-991) IllegalStateException during shutdown

Posted by "Tim-Christian Mundt (JIRA)" <se...@james.apache.org>.
    [ https://issues.apache.org/jira/browse/JAMES-991?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=12865756#action_12865756 ] 

Tim-Christian Mundt commented on JAMES-991:
-------------------------------------------

This also occurs on Linux (Ubuntu). I have to kill -9 the process to make it stop.

On Windows the java process takes approx 50% of the CPU and "System" the rest up to 100%. Don't know what kind of operation causes such a load.

> IllegalStateException during shutdown
> -------------------------------------
>
>                 Key: JAMES-991
>                 URL: https://issues.apache.org/jira/browse/JAMES-991
>             Project: JAMES Server
>          Issue Type: Bug
>          Components: James Core
>    Affects Versions: Trunk
>         Environment: WinXP
>            Reporter: Tim-Christian Mundt
>            Assignee: Norman Maurer
>             Fix For: 3.0-M1
>
>
> Sometimes (I love undetermined errors...) when shutting down James (current rev 936628) the CPU load goes very high and the system (Win XP) becomes totally unresponsive (although James only takes around 50% of the CPU (dual core)). I get this output:
> INFO  11:07:21,203 | org.apache.activemq.broker.BrokerService | ActiveMQ Message Broker (localhost, ID:timpad-1542-1272013579921-0:0) is shutting down
> INFO  11:07:21,203 | org.apache.james.container.spring.JamesServerApplicationContext | Closing org.apache.james.container.spring.JamesServer
> ApplicationContext@12498b5: startup date [Fri Apr 23 11:06:13 CEST 2010]; root of context hierarchy
> INFO  11:07:21,218 | james.imapserver | Dispose IMAP Service
> INFO  11:07:22,421 | org.apache.activemq.broker.TransportConnector | Connector openwire Stopped
> INFO  11:07:22,453 | org.apache.activemq.broker.TransportConnector | Connector vm://localhost Stopped
> INFO  11:07:23,921 | org.apache.activemq.broker.BrokerService | ActiveMQ JMS Message Broker (localhost, ID:timpad-1542-1272013579921-0:0) stopped
> INFO  11:07:24,781 | org.apache.activemq.broker.BrokerService | Using Persistence Adapter: AMQPersistenceAdapter(activemq-data\localhost)
> INFO  11:07:24,796 | org.apache.activemq.store.amq.AMQPersistenceAdapter | AMQStore starting using directory: activemq-data\localhost
> INFO  11:07:32,812 | org.apache.activemq.kaha.impl.KahaStore | Kaha Store using data directory activemq-data\localhost\kr-store\state
> INFO  11:08:54,515 | org.apache.activemq.store.amq.AMQPersistenceAdapter | Active data files: []
> And after a while this error follows:
> ERROR 11:09:15,140 | org.apache.activemq.broker.BrokerService | Failed to start ActiveMQ JMS Message Broker. Reason: java.lang.IllegalStateE
> xception: Shutdown in progress
> java.lang.IllegalStateException: Shutdown in progress
>         at java.lang.ApplicationShutdownHooks.add(Unknown Source)
>         at java.lang.Runtime.addShutdownHook(Unknown Source)
>         at org.apache.activemq.broker.BrokerService.addShutdownHook(BrokerService.java:1844)
>         at org.apache.activemq.broker.BrokerService.start(BrokerService.java:468)
>         at org.apache.activemq.transport.vm.VMTransportFactory.doCompositeConnect(VMTransportFactory.java:123)
>         at org.apache.activemq.transport.vm.VMTransportFactory.doConnect(VMTransportFactory.java:53)
>         at org.apache.activemq.transport.TransportFactory.doConnect(TransportFactory.java:51)
>         at org.apache.activemq.transport.TransportFactory.connect(TransportFactory.java:80)
>         at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:243)
>         at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:258)
>         at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:230)
>         at org.apache.activemq.ActiveMQConnectionFactory.createConnection(ActiveMQConnectionFactory.java:178)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:125)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:110)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:81)
>         at org.springframework.jms.support.JmsAccessor.createConnection(JmsAccessor.java:184)
>         at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:456)
>         at org.springframework.jms.core.JmsTemplate.receiveSelected(JmsTemplate.java:703)
>         at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:681)
>         at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:672)
>         at org.apache.camel.component.jms.JmsPollingConsumer.receive(JmsPollingConsumer.java:71)
>         at org.apache.camel.component.jms.JmsPollingConsumer.receiveNoWait(JmsPollingConsumer.java:53)
>         at org.apache.james.transport.camel.JMSSelectorPollingConsumer.poll(JMSSelectorPollingConsumer.java:76)
>         at org.apache.camel.impl.ScheduledPollConsumer.run(ScheduledPollConsumer.java:106)
>         at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source)
>         at java.util.concurrent.FutureTask$Sync.innerRunAndReset(Unknown Source)
>         at java.util.concurrent.FutureTask.runAndReset(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
>         at java.lang.Thread.run(Unknown Source)
> INFO  11:09:19,312 | org.apache.activemq.broker.BrokerService | ActiveMQ Message Broker (localhost, null) is shutting down
> I just tried again and after this stacktrace above I get this one:
> INFO  11:19:42,328 | org.apache.activemq.broker.BrokerService | ActiveMQ JMS Message Broker (localhost, null) stopped
> WARN  11:19:42,343 | org.apache.camel.impl.DefaultPollingConsumerPollStrategy | Consumer Consumer[pollingjms://queue?consumer.endpointUri=activemq%3Aqueue%3Aoutgoing.retry&delay=30000] could not poll endpoint: pollingjms://queue?consumer.endpointUri=activemq%3Aqueue%3Aoutgoing.retry&delay=30000 caused by: Uncategorized exception occured during JMS processing; nested exception is javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
> org.springframework.jms.UncategorizedJmsException: Uncategorized exception occured during JMS processing; nested exception is javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
>         at org.springframework.jms.support.JmsUtils.convertJmsAccessException(JmsUtils.java:316)
>         at org.springframework.jms.support.JmsAccessor.convertJmsAccessException(JmsAccessor.java:168)
>         at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:469)
>         at org.springframework.jms.core.JmsTemplate.receiveSelected(JmsTemplate.java:703)
>         at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:681)
>         at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:672)
>         at org.apache.camel.component.jms.JmsPollingConsumer.receive(JmsPollingConsumer.java:71)
>         at org.apache.camel.component.jms.JmsPollingConsumer.receiveNoWait(JmsPollingConsumer.java:53)
>         at org.apache.james.transport.camel.JMSSelectorPollingConsumer.poll(JMSSelectorPollingConsumer.java:76)
>         at org.apache.camel.impl.ScheduledPollConsumer.run(ScheduledPollConsumer.java:106)
>         at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source)
>         at java.util.concurrent.FutureTask$Sync.innerRunAndReset(Unknown Source)
>         at java.util.concurrent.FutureTask.runAndReset(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
>         at java.lang.Thread.run(Unknown Source)
> Caused by: javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
>         at org.apache.activemq.util.JMSExceptionSupport.create(JMSExceptionSupport.java:35)
>         at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:245)
>         at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:258)
>         at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:230)
>         at org.apache.activemq.ActiveMQConnectionFactory.createConnection(ActiveMQConnectionFactory.java:178)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:125)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:110)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:81)
>         at org.springframework.jms.support.JmsAccessor.createConnection(JmsAccessor.java:184)
>         at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:456)
>         ... 16 more
> Caused by: java.lang.IllegalStateException: Shutdown in progress
>         at java.lang.ApplicationShutdownHooks.add(Unknown Source)
>         at java.lang.Runtime.addShutdownHook(Unknown Source)
>         at org.apache.activemq.broker.BrokerService.addShutdownHook(BrokerService.java:1844)
>         at org.apache.activemq.broker.BrokerService.start(BrokerService.java:468)
>         at org.apache.activemq.transport.vm.VMTransportFactory.doCompositeConnect(VMTransportFactory.java:123)
>         at org.apache.activemq.transport.vm.VMTransportFactory.doConnect(VMTransportFactory.java:53)
>         at org.apache.activemq.transport.TransportFactory.doConnect(TransportFactory.java:51)
>         at org.apache.activemq.transport.TransportFactory.connect(TransportFactory.java:80)
>         at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:243)
>         ... 24 more
> INFO  11:20:13,312 | org.apache.activemq.broker.BrokerService | Using Persistence Adapter: AMQPersistenceAdapter(activemq-data\localhost)
> INFO  11:20:13,312 | org.apache.activemq.store.amq.AMQPersistenceAdapter | AMQStore starting using directory: activemq-data\localhost
> INFO  11:20:13,328 | org.apache.activemq.kaha.impl.KahaStore | Kaha Store using data directory activemq-data\localhost\kr-store\state
> INFO  11:20:13,359 | org.apache.activemq.store.amq.AMQPersistenceAdapter | Active data files: []
> Both these errors alternate with some pause in between until I kill the process. CPU-load is high all the time.

-- 
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.


---------------------------------------------------------------------
To unsubscribe, e-mail: server-dev-unsubscribe@james.apache.org
For additional commands, e-mail: server-dev-help@james.apache.org


[jira] Resolved: (JAMES-991) IllegalStateException during shutdown

Posted by "Norman Maurer (JIRA)" <se...@james.apache.org>.
     [ https://issues.apache.org/jira/browse/JAMES-991?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]

Norman Maurer resolved JAMES-991.
---------------------------------

    Resolution: Fixed

The problem with the cpu usage was fixed, so close this

> IllegalStateException during shutdown
> -------------------------------------
>
>                 Key: JAMES-991
>                 URL: https://issues.apache.org/jira/browse/JAMES-991
>             Project: JAMES Server
>          Issue Type: Bug
>          Components: James Core
>    Affects Versions: Trunk
>         Environment: WinXP
>            Reporter: Tim-Christian Mundt
>            Assignee: Norman Maurer
>             Fix For: 3.0-M1
>
>
> Sometimes (I love undetermined errors...) when shutting down James (current rev 936628) the CPU load goes very high and the system (Win XP) becomes totally unresponsive (although James only takes around 50% of the CPU (dual core)). I get this output:
> INFO  11:07:21,203 | org.apache.activemq.broker.BrokerService | ActiveMQ Message Broker (localhost, ID:timpad-1542-1272013579921-0:0) is shutting down
> INFO  11:07:21,203 | org.apache.james.container.spring.JamesServerApplicationContext | Closing org.apache.james.container.spring.JamesServer
> ApplicationContext@12498b5: startup date [Fri Apr 23 11:06:13 CEST 2010]; root of context hierarchy
> INFO  11:07:21,218 | james.imapserver | Dispose IMAP Service
> INFO  11:07:22,421 | org.apache.activemq.broker.TransportConnector | Connector openwire Stopped
> INFO  11:07:22,453 | org.apache.activemq.broker.TransportConnector | Connector vm://localhost Stopped
> INFO  11:07:23,921 | org.apache.activemq.broker.BrokerService | ActiveMQ JMS Message Broker (localhost, ID:timpad-1542-1272013579921-0:0) stopped
> INFO  11:07:24,781 | org.apache.activemq.broker.BrokerService | Using Persistence Adapter: AMQPersistenceAdapter(activemq-data\localhost)
> INFO  11:07:24,796 | org.apache.activemq.store.amq.AMQPersistenceAdapter | AMQStore starting using directory: activemq-data\localhost
> INFO  11:07:32,812 | org.apache.activemq.kaha.impl.KahaStore | Kaha Store using data directory activemq-data\localhost\kr-store\state
> INFO  11:08:54,515 | org.apache.activemq.store.amq.AMQPersistenceAdapter | Active data files: []
> And after a while this error follows:
> ERROR 11:09:15,140 | org.apache.activemq.broker.BrokerService | Failed to start ActiveMQ JMS Message Broker. Reason: java.lang.IllegalStateE
> xception: Shutdown in progress
> java.lang.IllegalStateException: Shutdown in progress
>         at java.lang.ApplicationShutdownHooks.add(Unknown Source)
>         at java.lang.Runtime.addShutdownHook(Unknown Source)
>         at org.apache.activemq.broker.BrokerService.addShutdownHook(BrokerService.java:1844)
>         at org.apache.activemq.broker.BrokerService.start(BrokerService.java:468)
>         at org.apache.activemq.transport.vm.VMTransportFactory.doCompositeConnect(VMTransportFactory.java:123)
>         at org.apache.activemq.transport.vm.VMTransportFactory.doConnect(VMTransportFactory.java:53)
>         at org.apache.activemq.transport.TransportFactory.doConnect(TransportFactory.java:51)
>         at org.apache.activemq.transport.TransportFactory.connect(TransportFactory.java:80)
>         at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:243)
>         at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:258)
>         at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:230)
>         at org.apache.activemq.ActiveMQConnectionFactory.createConnection(ActiveMQConnectionFactory.java:178)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:125)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:110)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:81)
>         at org.springframework.jms.support.JmsAccessor.createConnection(JmsAccessor.java:184)
>         at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:456)
>         at org.springframework.jms.core.JmsTemplate.receiveSelected(JmsTemplate.java:703)
>         at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:681)
>         at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:672)
>         at org.apache.camel.component.jms.JmsPollingConsumer.receive(JmsPollingConsumer.java:71)
>         at org.apache.camel.component.jms.JmsPollingConsumer.receiveNoWait(JmsPollingConsumer.java:53)
>         at org.apache.james.transport.camel.JMSSelectorPollingConsumer.poll(JMSSelectorPollingConsumer.java:76)
>         at org.apache.camel.impl.ScheduledPollConsumer.run(ScheduledPollConsumer.java:106)
>         at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source)
>         at java.util.concurrent.FutureTask$Sync.innerRunAndReset(Unknown Source)
>         at java.util.concurrent.FutureTask.runAndReset(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
>         at java.lang.Thread.run(Unknown Source)
> INFO  11:09:19,312 | org.apache.activemq.broker.BrokerService | ActiveMQ Message Broker (localhost, null) is shutting down
> I just tried again and after this stacktrace above I get this one:
> INFO  11:19:42,328 | org.apache.activemq.broker.BrokerService | ActiveMQ JMS Message Broker (localhost, null) stopped
> WARN  11:19:42,343 | org.apache.camel.impl.DefaultPollingConsumerPollStrategy | Consumer Consumer[pollingjms://queue?consumer.endpointUri=activemq%3Aqueue%3Aoutgoing.retry&delay=30000] could not poll endpoint: pollingjms://queue?consumer.endpointUri=activemq%3Aqueue%3Aoutgoing.retry&delay=30000 caused by: Uncategorized exception occured during JMS processing; nested exception is javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
> org.springframework.jms.UncategorizedJmsException: Uncategorized exception occured during JMS processing; nested exception is javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
>         at org.springframework.jms.support.JmsUtils.convertJmsAccessException(JmsUtils.java:316)
>         at org.springframework.jms.support.JmsAccessor.convertJmsAccessException(JmsAccessor.java:168)
>         at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:469)
>         at org.springframework.jms.core.JmsTemplate.receiveSelected(JmsTemplate.java:703)
>         at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:681)
>         at org.springframework.jms.core.JmsTemplate.receive(JmsTemplate.java:672)
>         at org.apache.camel.component.jms.JmsPollingConsumer.receive(JmsPollingConsumer.java:71)
>         at org.apache.camel.component.jms.JmsPollingConsumer.receiveNoWait(JmsPollingConsumer.java:53)
>         at org.apache.james.transport.camel.JMSSelectorPollingConsumer.poll(JMSSelectorPollingConsumer.java:76)
>         at org.apache.camel.impl.ScheduledPollConsumer.run(ScheduledPollConsumer.java:106)
>         at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source)
>         at java.util.concurrent.FutureTask$Sync.innerRunAndReset(Unknown Source)
>         at java.util.concurrent.FutureTask.runAndReset(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(Unknown Source)
>         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
>         at java.lang.Thread.run(Unknown Source)
> Caused by: javax.jms.JMSException: Could not create Transport. Reason: java.lang.IllegalStateException: Shutdown in progress
>         at org.apache.activemq.util.JMSExceptionSupport.create(JMSExceptionSupport.java:35)
>         at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:245)
>         at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:258)
>         at org.apache.activemq.ActiveMQConnectionFactory.createActiveMQConnection(ActiveMQConnectionFactory.java:230)
>         at org.apache.activemq.ActiveMQConnectionFactory.createConnection(ActiveMQConnectionFactory.java:178)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:125)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:110)
>         at org.apache.activemq.pool.PooledConnectionFactory.createConnection(PooledConnectionFactory.java:81)
>         at org.springframework.jms.support.JmsAccessor.createConnection(JmsAccessor.java:184)
>         at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:456)
>         ... 16 more
> Caused by: java.lang.IllegalStateException: Shutdown in progress
>         at java.lang.ApplicationShutdownHooks.add(Unknown Source)
>         at java.lang.Runtime.addShutdownHook(Unknown Source)
>         at org.apache.activemq.broker.BrokerService.addShutdownHook(BrokerService.java:1844)
>         at org.apache.activemq.broker.BrokerService.start(BrokerService.java:468)
>         at org.apache.activemq.transport.vm.VMTransportFactory.doCompositeConnect(VMTransportFactory.java:123)
>         at org.apache.activemq.transport.vm.VMTransportFactory.doConnect(VMTransportFactory.java:53)
>         at org.apache.activemq.transport.TransportFactory.doConnect(TransportFactory.java:51)
>         at org.apache.activemq.transport.TransportFactory.connect(TransportFactory.java:80)
>         at org.apache.activemq.ActiveMQConnectionFactory.createTransport(ActiveMQConnectionFactory.java:243)
>         ... 24 more
> INFO  11:20:13,312 | org.apache.activemq.broker.BrokerService | Using Persistence Adapter: AMQPersistenceAdapter(activemq-data\localhost)
> INFO  11:20:13,312 | org.apache.activemq.store.amq.AMQPersistenceAdapter | AMQStore starting using directory: activemq-data\localhost
> INFO  11:20:13,328 | org.apache.activemq.kaha.impl.KahaStore | Kaha Store using data directory activemq-data\localhost\kr-store\state
> INFO  11:20:13,359 | org.apache.activemq.store.amq.AMQPersistenceAdapter | Active data files: []
> Both these errors alternate with some pause in between until I kill the process. CPU-load is high all the time.

-- 
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.


---------------------------------------------------------------------
To unsubscribe, e-mail: server-dev-unsubscribe@james.apache.org
For additional commands, e-mail: server-dev-help@james.apache.org