Need more descriptive logging during boot with high Number Of Pending Messages
------------------------------------------------------------------------------
Key: AMQ-2116
URL: https://issues.apache.org/activemq/browse/AMQ-2116
Project: ActiveMQ
Issue Type: Improvement
Components: Broker
Affects Versions: 5.2.0
Environment: Linux, red hat
Reporter: Erik Drolshammer
When restarting the jms server and "Number Of Pending Messages " is high, it is
hard to guess from the log what's going on.
A couple of questions/suggestions:
* After "INFO [KahaStore] Kaha Store using data directory
/usr/local/apache-activemq-5.2.0/data/localhost/kr-store/data" is printed,
there can go a long time (we have experience up to one hour) before the next
info statement.
** Perhaps it is a good idea to print something explaining what's going on?
* Why print lastDataFile: 182 repeatedly? Since the id doesn't change, why not
only print it once?
* Would it be possible to add some more explanation to "Checkpoint started."
and "Checkpoint done."? What purpose has these checkpoints?
10:35:33, 10:39:04
2009-02-16 10:35:32,329 DEBUG [XBeanBrokerFactory] Now attempting to figure out
the type of resource: activemq.xml
2009-02-16 10:35:32,385 INFO [ResourceXmlApplicationContext] Refreshing
org.apache.xbean.spring.context.resourcexmlapplicationcont...@179935d: display
name [org.apache.xbean.spring.context.resourcexmlapplicationcont...@179935d];
startup date [Mon Feb 16 10:35:32 GMT+01:00 2009]; root of context hierarchy
2009-02-16 10:35:32,488 INFO [XBeanXmlBeanDefinitionReader] Loading XML bean
definitions from class path resource [activemq.xml]
2009-02-16 10:35:32,848 INFO [ResourceXmlApplicationContext] Bean factory for
application context
[org.apache.xbean.spring.context.resourcexmlapplicationcont...@179935d]:
org.springframework.beans.factory.support.defaultlistablebeanfact...@131c89c
2009-02-16 10:35:33,043 INFO [BrokerService] Using Persistence Adapter:
AMQPersistenceAdapter(/usr/local/apache-activemq-5.2.0/data/localhost)
2009-02-16 10:35:33,051 INFO [AMQPersistenceAdapter] AMQStore starting using
directory: /usr/local/apache-activemq-5.2.0/data/localhost
2009-02-16 10:35:33,093 INFO [KahaStore] Kaha Store using data directory
/usr/local/apache-activemq-5.2.0/data/localhost/kr-store/state
2009-02-16 10:35:33,159 INFO [AMQPersistenceAdapter] Active data files: [137,
136, 139, 138, 141, 140, 143, 142, 129, 128, 131, 130, 133, 132, 135, 134, 152,
153, 154, 155, 156, 157, 158, 159, 144, 145, 146, 147, 148, 149, 150, 151, 171,
170, 169, 168, 175, 174, 173, 172, 163, 162, 161, 160, 167, 166, 165, 164, 178,
179, 176, 177, 182, 180, 181, 127, 126, 125, 124, 123, 122]
2009-02-16 10:35:33,204 INFO [BrokerService] ActiveMQ 5.2.0 JMS Message Broker
(localhost) is starting
2009-02-16 10:35:33,204 INFO [BrokerService] For help or more information
please see: http://activemq.apache.org/
2009-02-16 10:35:33,205 DEBUG [AMQPersistenceAdapter] Checkpoint started.
2009-02-16 10:35:33,206 DEBUG [AMQPersistenceAdapter] Checkpoint done.
2009-02-16 10:35:33,389 INFO [KahaStore] Kaha Store using data directory
/usr/local/apache-activemq-5.2.0/data/localhost/kr-store/data
2009-02-16 10:35:53,202 DEBUG [AMQPersistenceAdapter] Checkpoint started.
2009-02-16 10:35:53,203 DEBUG [AMQPersistenceAdapter] Checkpoint done.
2009-02-16 10:36:03,203 DEBUG [AMQPersistenceAdapter]
dataFilesInProgress.values: (0) []
2009-02-16 10:36:03,203 DEBUG [AMQPersistenceAdapter] lastDataFile: 182
2009-02-16 10:36:03,203 DEBUG [AsyncDataManager] lastFileId=181, purgeList: (0)
[]
2009-02-16 10:36:13,201 DEBUG [AMQPersistenceAdapter] Checkpoint started.
2009-02-16 10:36:13,202 DEBUG [AMQPersistenceAdapter] Checkpoint done.
2009-02-16 10:36:33,202 DEBUG [AMQPersistenceAdapter]
dataFilesInProgress.values: (0) []
2009-02-16 10:36:33,203 DEBUG [AMQPersistenceAdapter] lastDataFile: 182
2009-02-16 10:36:33,203 DEBUG [AsyncDataManager] lastFileId=181, purgeList: (0)
[]
2009-02-16 10:36:33,203 DEBUG [AMQPersistenceAdapter] Checkpoint started.
2009-02-16 10:36:33,203 DEBUG [AMQPersistenceAdapter] Checkpoint done.
2009-02-16 10:36:35,536 DEBUG [AbstractRegion] Adding destination:
queue://app1.jobqueue
2009-02-16 10:36:35,603 DEBUG [AbstractRegion] Adding destination:
topic://ActiveMQ.Advisory.Queue
2009-02-16 10:36:35,619 DEBUG [AbstractRegion] Adding destination:
queue://app1.resultqueue
2009-02-16 10:36:53,202 DEBUG [AMQPersistenceAdapter] Checkpoint started.
2009-02-16 10:36:53,203 DEBUG [AMQPersistenceAdapter] Checkpoint done.
2009-02-16 10:37:03,204 DEBUG [AMQPersistenceAdapter]
dataFilesInProgress.values: (0) []
2009-02-16 10:37:03,205 DEBUG [AMQPersistenceAdapter] lastDataFile: 182
2009-02-16 10:37:03,205 DEBUG [AsyncDataManager] lastFileId=181, purgeList: (0)
[]
2009-02-16 10:37:13,202 DEBUG [AMQPersistenceAdapter] Checkpoint started.
2009-02-16 10:37:13,202 DEBUG [AMQPersistenceAdapter] Checkpoint done.
2009-02-16 10:37:33,203 DEBUG [AMQPersistenceAdapter]
dataFilesInProgress.values: (0) []
2009-02-16 10:37:33,203 DEBUG [AMQPersistenceAdapter] lastDataFile: 182
2009-02-16 10:37:33,203 DEBUG [AsyncDataManager] lastFileId=181, purgeList: (0)
[]
2009-02-16 10:37:33,204 DEBUG [AMQPersistenceAdapter] Checkpoint started.
2009-02-16 10:37:33,204 DEBUG [AMQPersistenceAdapter] Checkpoint done.
2009-02-16 10:37:53,202 DEBUG [AMQPersistenceAdapter] Checkpoint started.
2009-02-16 10:37:53,202 DEBUG [AMQPersistenceAdapter] Checkpoint done.
2009-02-16 10:38:03,203 DEBUG [AMQPersistenceAdapter]
dataFilesInProgress.values: (0) []
2009-02-16 10:38:03,204 DEBUG [AMQPersistenceAdapter] lastDataFile: 182
2009-02-16 10:38:03,204 DEBUG [AsyncDataManager] lastFileId=181, purgeList: (0)
[]
2009-02-16 10:38:13,202 DEBUG [AMQPersistenceAdapter] Checkpoint started.
2009-02-16 10:38:13,202 DEBUG [AMQPersistenceAdapter] Checkpoint done.
2009-02-16 10:38:33,202 DEBUG [AMQPersistenceAdapter]
dataFilesInProgress.values: (0) []
2009-02-16 10:38:33,203 DEBUG [AMQPersistenceAdapter] lastDataFile: 182
2009-02-16 10:38:33,203 DEBUG [AsyncDataManager] lastFileId=181, purgeList: (0)
[]
2009-02-16 10:38:33,204 DEBUG [AMQPersistenceAdapter] Checkpoint started.
2009-02-16 10:38:33,204 DEBUG [AMQPersistenceAdapter] Checkpoint done.
2009-02-16 10:38:53,201 DEBUG [AMQPersistenceAdapter] Checkpoint started.
2009-02-16 10:38:53,201 DEBUG [AMQPersistenceAdapter] Checkpoint done.
2009-02-16 10:39:03,203 DEBUG [AMQPersistenceAdapter]
dataFilesInProgress.values: (0) []
2009-02-16 10:39:03,203 DEBUG [AMQPersistenceAdapter] lastDataFile: 182
2009-02-16 10:39:03,203 DEBUG [AsyncDataManager] lastFileId=181, purgeList: (0)
[]
2009-02-16 10:39:04,925 DEBUG [AbstractRegion] Adding destination:
queue://app1.signalqueue
2009-02-16 10:39:04,962 INFO [TransportServerThreadSupport] Listening for
connections at: tcp://hostname1:61616
2009-02-16 10:39:04,976 INFO [TransportConnector] Connector openwire Started
2009-02-16 10:39:04,980 INFO [NetworkConnector] Network Connector default-nc
Started
2009-02-16 10:39:04,981 INFO [BrokerService] ActiveMQ JMS Message Broker
(localhost, ID:hostname1-52281-1234776933240-0:0) started
2009-02-16 10:39:05,044 INFO [log] Logging to
org.slf4j.impl.JCLLoggerAdapter(org.mortbay.log) via org.mortbay.log.Slf4jLog
--
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.
