activemq-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Hans (JIRA)" <j...@apache.org>
Subject [jira] Created: (AMQ-1821) Https JMSBridgeConnector failed to recover due to an infinite loop in Journal cleanup.
Date Sat, 21 Jun 2008 01:13:00 GMT
Https JMSBridgeConnector failed to recover due to an infinite loop in Journal cleanup.
--------------------------------------------------------------------------------------

                 Key: AMQ-1821
                 URL: https://issues.apache.org/activemq/browse/AMQ-1821
             Project: ActiveMQ
          Issue Type: Bug
          Components: Broker
    Affects Versions: 4.1.1
         Environment: Win XP
            Reporter: Hans
            Priority: Critical
         Attachments: activemq.log

Hi, 
I am not sure if this is an known bug.
I am encountering an issue when I have a broker with a Https Queue Bridge connector in several
machines connected with one embedded broker in a remote Tomcat server.  Messages are being
sent from client machines to the server with no connectivity issue in the first couple days(well,
not really, the time it takes to fail varies).  During this first period of time, the failover
transport was able to recover from connection failures.  However, the brokers' https connections
started to drop after time and not being able to recover anymore.  So It's not able to send
messages to the server anymore.  The debug logs shows an infinite loop of checkpoints(msgs
cleanups) with the Journal Persistence.  This also happens with Derby Persistence.  I am not
sure if this infinite loop prevents the failover to take place in this case.  As a note, the
time it takes to reach this failure case varies in different client machines, but they are
sending messages to the server at the same rate(1 message per hour).

I have attached a partial text of the log.

2008-06-20 04:03:57,630 [r.emn2008.com/amq/] WARN  HttpClientTransport            - Received
null command from url: https://publisher.emn2008.com/amq/
2008-06-20 04:03:57,630 [r.emn2008.com/amq/] DEBUG HttpMethodBase                 - Buffering
response body
2008-06-20 04:03:57,630 [r.emn2008.com/amq/] DEBUG HttpMethodBase                 - Resorting
to protocol version default close connection policy
2008-06-20 04:03:57,630 [r.emn2008.com/amq/] DEBUG HttpMethodBase                 - Should
NOT close connection, using HTTP/1.1.
2008-06-20 04:03:57,630 [r.emn2008.com/amq/] DEBUG HttpConnection                 - HttpConnection.setSoTimeout(0)
2008-06-20 04:03:57,630 [r.emn2008.com/amq/] DEBUG HttpMethodBase                 - Execute
loop try 1
2008-06-20 04:03:57,630 [r.emn2008.com/amq/] DEBUG header                         - >>
"GET /amq/ HTTP/1.1[\r][\n]"
2008-06-20 04:03:57,630 [r.emn2008.com/amq/] DEBUG HttpMethodBase                 - Adding
Host request header
2008-06-20 04:03:57,630 [r.emn2008.com/amq/] DEBUG header                         - >>
"clientID: ID:emn2008-82646A4E67-2374-1213758751348-5:5[\r][\n]"
2008-06-20 04:03:57,630 [r.emn2008.com/amq/] DEBUG header                         - >>
"User-Agent: Jakarta Commons-HttpClient/2.0.1[\r][\n]"
2008-06-20 04:03:57,630 [r.emn2008.com/amq/] DEBUG header                         - >>
"Host: publisher.emn2008.com[\r][\n]"
2008-06-20 04:03:57,630 [r.emn2008.com/amq/] DEBUG header                         - >>
"[\r][\n]"
2008-06-20 04:07:16,505 [eckpoint Worker] DEBUG JournalPersistenceAdapter      - Checkpoint
started.
2008-06-20 04:07:16,505 [eckpoint Worker] DEBUG JDBCPersistenceAdapter         - Cleaning
up old messages.
2008-06-20 04:07:16,505 [eckpoint Worker] DEBUG DefaultJDBCAdapter             - Executing
SQL: DELETE FROM ACTIVEMQ_MSGS WHERE ( EXPIRATION<>0 AND EXPIRATION<?) OR ID <=
( SELECT min(ACTIVEMQ_ACKS.LAST_ACKED_ID) FROM ACTIVEMQ_ACKS WHERE ACTIVEMQ_ACKS.CONTAINER=ACTIVEMQ_MSGS.CONTAINER)
2008-06-20 04:07:16,505 [eckpoint Worker] DEBUG DefaultJDBCAdapter             - Deleted 0
old message(s).
2008-06-20 04:07:16,505 [eckpoint Worker] DEBUG JDBCPersistenceAdapter         - Cleanup done.
2008-06-20 04:07:16,505 [eckpoint Worker] DEBUG JournalPersistenceAdapter      - Checkpoint
done.
2008-06-20 04:07:48,489 [Q Cleanup Timer] DEBUG JDBCPersistenceAdapter         - Cleaning
up old messages.
2008-06-20 04:07:48,489 [Q Cleanup Timer] DEBUG DefaultJDBCAdapter             - Executing
SQL: DELETE FROM ACTIVEMQ_MSGS WHERE ( EXPIRATION<>0 AND EXPIRATION<?) OR ID <=
( SELECT min(ACTIVEMQ_ACKS.LAST_ACKED_ID) FROM ACTIVEMQ_ACKS WHERE ACTIVEMQ_ACKS.CONTAINER=ACTIVEMQ_MSGS.CONTAINER)
2008-06-20 04:07:48,489 [Q Cleanup Timer] DEBUG DefaultJDBCAdapter             - Deleted 0
old message(s).
2008-06-20 04:07:48,489 [Q Cleanup Timer] DEBUG JDBCPersistenceAdapter         - Cleanup done.
2008-06-20 04:12:16,520 [eckpoint Worker] DEBUG JournalPersistenceAdapter      - Checkpoint
started.
2008-06-20 04:12:16,520 [eckpoint Worker] DEBUG JDBCPersistenceAdapter         - Cleaning
up old messages.
2008-06-20 04:12:16,520 [eckpoint Worker] DEBUG DefaultJDBCAdapter             - Executing
SQL: DELETE FROM ACTIVEMQ_MSGS WHERE ( EXPIRATION<>0 AND EXPIRATION<?) OR ID <=
( SELECT min(ACTIVEMQ_ACKS.LAST_ACKED_ID) FROM ACTIVEMQ_ACKS WHERE ACTIVEMQ_ACKS.CONTAINER=ACTIVEMQ_MSGS.CONTAINER)
2008-06-20 04:12:16,520 [eckpoint Worker] DEBUG DefaultJDBCAdapter             - Deleted 0
old message(s).
2008-06-20 04:12:16,520 [eckpoint Worker] DEBUG JDBCPersistenceAdapter         - Cleanup done.
2008-06-20 04:12:16,520 [eckpoint Worker] DEBUG JournalPersistenceAdapter      - Checkpoint
done.
2008-06-20 04:12:48,505 [Q Cleanup Timer] DEBUG JDBCPersistenceAdapter         - Cleaning
up old messages.
2008-06-20 04:12:48,505 [Q Cleanup Timer] DEBUG DefaultJDBCAdapter             - Executing
SQL: DELETE FROM ACTIVEMQ_MSGS WHERE ( EXPIRATION<>0 AND EXPIRATION<?) OR ID <=
( SELECT min(ACTIVEMQ_ACKS.LAST_ACKED_ID) FROM ACTIVEMQ_ACKS WHERE ACTIVEMQ_ACKS.CONTAINER=ACTIVEMQ_MSGS.CONTAINER)
2008-06-20 04:12:48,505 [Q Cleanup Timer] DEBUG DefaultJDBCAdapter             - Deleted 0
old message(s).
2008-06-20 04:12:48,505 [Q Cleanup Timer] DEBUG JDBCPersistenceAdapter         - Cleanup done.
2008-06-20 04:17:16,536 [eckpoint Worker] DEBUG JournalPersistenceAdapter      - Checkpoint
started.
2008-06-20 04:17:16,536 [eckpoint Worker] DEBUG JDBCPersistenceAdapter         - Cleaning
up old messages.
2008-06-20 04:17:16,536 [eckpoint Worker] DEBUG DefaultJDBCAdapter             - Executing
SQL: DELETE FROM ACTIVEMQ_MSGS WHERE ( EXPIRATION<>0 AND EXPIRATION<?) OR ID <=
( SELECT min(ACTIVEMQ_ACKS.LAST_ACKED_ID) FROM ACTIVEMQ_ACKS WHERE ACTIVEMQ_ACKS.CONTAINER=ACTIVEMQ_MSGS.CONTAINER)
2008-06-20 04:17:16,536 [eckpoint Worker] DEBUG DefaultJDBCAdapter             - Deleted 0
old message(s).
2008-06-20 04:17:16,536 [eckpoint Worker] DEBUG JDBCPersistenceAdapter         - Cleanup done.
2008-06-20 04:17:16,536 [eckpoint Worker] DEBUG JournalPersistenceAdapter      - Checkpoint
done.
2008-06-20 04:17:48,520 [Q Cleanup Timer] DEBUG JDBCPersistenceAdapter         - Cleaning
up old messages.
2008-06-20 04:17:48,520 [Q Cleanup Timer] DEBUG DefaultJDBCAdapter             - Executing
SQL: DELETE FROM ACTIVEMQ_MSGS WHERE ( EXPIRATION<>0 AND EXPIRATION<?) OR ID <=
( SELECT min(ACTIVEMQ_ACKS.LAST_ACKED_ID) FROM ACTIVEMQ_ACKS WHERE ACTIVEMQ_ACKS.CONTAINER=ACTIVEMQ_MSGS.CONTAINER)
2008-06-20 04:17:48,520 [Q Cleanup Timer] DEBUG DefaultJDBCAdapter             - Deleted 0
old message(s).
2008-06-20 04:17:48,520 [Q Cleanup Timer] DEBUG JDBCPersistenceAdapter         - Cleanup done.
2008-06-20 04:22:46,552 [eckpoint Worker] DEBUG JournalPersistenceAdapter      - Checkpoint
started.
2008-06-20 04:22:46,552 [eckpoint Worker] DEBUG JDBCPersistenceAdapter         - Cleaning
up old messages.
2008-06-20 04:22:46,552 [eckpoint Worker] DEBUG DefaultJDBCAdapter             - Executing
SQL: DELETE FROM ACTIVEMQ_MSGS WHERE ( EXPIRATION<>0 AND EXPIRATION<?) OR ID <=
( SELECT min(ACTIVEMQ_ACKS.LAST_ACKED_ID) FROM ACTIVEMQ_ACKS WHERE ACTIVEMQ_ACKS.CONTAINER=ACTIVEMQ_MSGS.CONTAINER)
2008-06-20 04:22:46,552 [eckpoint Worker] DEBUG DefaultJDBCAdapter             - Deleted 0
old message(s).
2008-06-20 04:22:46,552 [eckpoint Worker] DEBUG JDBCPersistenceAdapter         - Cleanup done.
2008-06-20 04:22:46,552 [eckpoint Worker] DEBUG JournalPersistenceAdapter      - Checkpoint
done.
2008-06-20 04:22:48,536 [Q Cleanup Timer] DEBUG JDBCPersistenceAdapter         - Cleaning
up old messages.
2008-06-20 04:22:48,536 [Q Cleanup Timer] DEBUG DefaultJDBCAdapter             - Executing
SQL: DELETE FROM ACTIVEMQ_MSGS WHERE ( EXPIRATION<>0 AND EXPIRATION<?) OR ID <=
( SELECT min(ACTIVEMQ_ACKS.LAST_ACKED_ID) FROM ACTIVEMQ_ACKS WHERE ACTIVEMQ_ACKS.CONTAINER=ACTIVEMQ_MSGS.CONTAINER)
2008-06-20 04:22:48,536 [Q Cleanup Timer] DEBUG DefaultJDBCAdapter             - Deleted 0
old message(s).
2008-06-20 04:22:48,536 [Q Cleanup Timer] DEBUG JDBCPersistenceAdapter         - Cleanup done.
2008-06-20 04:27:48,552 [Q Cleanup Timer] DEBUG JDBCPersistenceAdapter         - Cleaning
up old messages.
2008-06-20 04:27:48,552 [Q Cleanup Timer] DEBUG DefaultJDBCAdapter             - Executing
SQL: DELETE FROM ACTIVEMQ_MSGS WHERE ( EXPIRATION<>0 AND EXPIRATION<?) OR ID <=
( SELECT min(ACTIVEMQ_ACKS.LAST_ACKED_ID) FROM ACTIVEMQ_ACKS WHERE ACTIVEMQ_ACKS.CONTAINER=ACTIVEMQ_MSGS.CONTAINER)
2008-06-20 04:27:48,552 [Q Cleanup Timer] DEBUG DefaultJDBCAdapter             - Deleted 0
old message(s).
2008-06-20 04:27:48,552 [Q Cleanup Timer] DEBUG JDBCPersistenceAdapter         - Cleanup done.
2008-06-20 04:28:16,552 [eckpoint Worker] DEBUG JournalPersistenceAdapter      - Checkpoint
started.
2008-06-20 04:28:16,567 [eckpoint Worker] DEBUG JDBCPersistenceAdapter         - Cleaning
up old messages.
2008-06-20 04:28:16,567 [eckpoint Worker] DEBUG DefaultJDBCAdapter             - Executing
SQL: DELETE FROM ACTIVEMQ_MSGS WHERE ( EXPIRATION<>0 AND EXPIRATION<?) OR ID <=
( SELECT min(ACTIVEMQ_ACKS.LAST_ACKED_ID) FROM ACTIVEMQ_ACKS WHERE ACTIVEMQ_ACKS.CONTAINER=ACTIVEMQ_MSGS.CONTAINER)
2008-06-20 04:28:16,567 [eckpoint Worker] DEBUG DefaultJDBCAdapter             - Deleted 0
old message(s).
2008-06-20 04:28:16,567 [eckpoint Worker] DEBUG JDBCPersistenceAdapter         - Cleanup done.
2008-06-20 04:28:16,567 [eckpoint Worker] DEBUG JournalPersistenceAdapter      - Checkpoint
done.
2008-06-20 04:32:48,552 [Q Cleanup Timer] DEBUG JDBCPersistenceAdapter         - Cleaning
up old messages.
2008-06-20 04:32:48,552 [Q Cleanup Timer] DEBUG DefaultJDBCAdapter             - Executing
SQL: DELETE FROM ACTIVEMQ_MSGS WHERE ( EXPIRATION<>0 AND EXPIRATION<?) OR ID <=
( SELECT min(ACTIVEMQ_ACKS.LAST_ACKED_ID) FROM ACTIVEMQ_ACKS WHERE ACTIVEMQ_ACKS.CONTAINER=ACTIVEMQ_MSGS.CONTAINER)
2008-06-20 04:32:48,567 [Q Cleanup Timer] DEBUG DefaultJDBCAdapter             - Deleted 0
old message(s).
2008-06-20 04:32:48,567 [Q Cleanup Timer] DEBUG JDBCPersistenceAdapter         - Cleanup done.
2008-06-20 04:33:16,567 [eckpoint Worker] DEBUG JournalPersistenceAdapter      - Checkpoint
started.
2008-06-20 04:33:16,567 [eckpoint Worker] DEBUG JournalPersistenceAdapter      - Checkpoint
done.
2008-06-20 04:37:48,567 [Q Cleanup Timer] DEBUG JDBCPersistenceAdapter         - Cleaning
up old messages.
2008-06-20 04:37:48,567 [Q Cleanup Timer] DEBUG DefaultJDBCAdapter             - Executing
SQL: DELETE FROM ACTIVEMQ_MSGS WHERE ( EXPIRATION<>0 AND EXPIRATION<?) OR ID <=
( SELECT min(ACTIVEMQ_ACKS.LAST_ACKED_ID) FROM ACTIVEMQ_ACKS WHERE ACTIVEMQ_ACKS.CONTAINER=ACTIVEMQ_MSGS.CONTAINER)
2008-06-20 04:37:48,567 [Q Cleanup Timer] DEBUG DefaultJDBCAdapter             - Deleted 0
old message(s).
2008-06-20 04:37:48,567 [Q Cleanup Timer] DEBUG JDBCPersistenceAdapter         - Cleanup done.
2008-06-20 04:38:16,583 [eckpoint Worker] DEBUG JournalPersistenceAdapter      - Checkpoint
started.
2008-06-20 04:38:16,583 [eckpoint Worker] DEBUG JDBCPersistenceAdapter         - Cleaning
up old messages.
2008-06-20 04:38:16,583 [eckpoint Worker] DEBUG DefaultJDBCAdapter             - Executing
SQL: DELETE FROM ACTIVEMQ_MSGS WHERE ( EXPIRATION<>0 AND EXPIRATION<?) OR ID <=
( SELECT min(ACTIVEMQ_ACKS.LAST_ACKED_ID) FROM ACTIVEMQ_ACKS WHERE ACTIVEMQ_ACKS.CONTAINER=ACTIVEMQ_MSGS.CONTAINER)
2008-06-20 04:38:16,583 [eckpoint Worker] DEBUG DefaultJDBCAdapter             - Deleted 0
old message(s).
2008-06-20 04:38:16,583 [eckpoint Worker] DEBUG JDBCPersistenceAdapter         - Cleanup done.
2008-06-20 04:38:16,583 [eckpoint Worker] DEBUG JournalPersistenceAdapter      - Checkpoint
done.
2008-06-20 04:42:11,989 [/127.0.0.1:3106] DEBUG AbstractRegion                 - Removing
consumer: ID:emn2008-82646A4E67-3632-1213901674708-1:47:-1:1
2008-06-20 04:42:11,989 [/127.0.0.1:3106] DEBUG TransportConnection            - Stopping
connection: /127.0.0.1:3106
2008-06-20 04:42:11,989 [/127.0.0.1:3106] DEBUG TransportConnection            - Stopped connection:
/127.0.0.1:3106
2008-06-20 04:42:12,005 [localhost:60001] DEBUG WireFormatNegotiator           - Sending:
WireFormatInfo { version=2, properties={CacheSize=1024, CacheEnabled=true, SizePrefixDisabled=false,
TcpNoDelayEnabled=true, MaxInactivityDuration=30000, TightEncodingEnabled=true, StackTraceEnabled=true},
magic=[A,c,t,i,v,e,M,Q]}
2008-06-20 04:42:12,005 [/127.0.0.1:3128] DEBUG WireFormatNegotiator           - Received
WireFormat: WireFormatInfo { version=2, properties={CacheSize=1024, CacheEnabled=true, SizePrefixDisabled=false,
TcpNoDelayEnabled=true, MaxInactivityDuration=30000, TightEncodingEnabled=true, StackTraceEnabled=true},
magic=[A,c,t,i,v,e,M,Q]}
2008-06-20 04:42:12,005 [/127.0.0.1:3128] DEBUG WireFormatNegotiator           - tcp:///127.0.0.1:3128
before negotiation: OpenWireFormat{version=2, cacheEnabled=false, stackTraceEnabled=false,
tightEncodingEnabled=false, sizePrefixDisabled=false}
2008-06-20 04:42:12,005 [/127.0.0.1:3128] DEBUG WireFormatNegotiator           - tcp:///127.0.0.1:3128
after negotiation: OpenWireFormat{version=2, cacheEnabled=true, stackTraceEnabled=true, tightEncodingEnabled=true,
sizePrefixDisabled=false}
2008-06-20 04:42:12,005 [/127.0.0.1:3128] DEBUG TransportConnection            - Setting up
new connection: org.apache.activemq.broker.jmx.ManagedTransportConnection@1e9d810
2008-06-20 04:42:12,005 [/127.0.0.1:3128] DEBUG AbstractRegion                 - Adding consumer:
ID:emn2008-82646A4E67-3632-1213901674708-1:48:-1:1
2008-06-20 04:42:12,036 [758751348-3:1:2] DEBUG ActiveMQSession                - Sending message:
ActiveMQTextMessage {commandId = 5, responseRequired = true, messageId = ID:emn2008-82646A4E67-2374-1213758751348-3:0:2:1:172,
originalDestination = null, originalTransactionId = null, producerId = ID:emn2008-82646A4E67-2374-1213758751348-3:0:2:1,
destination = queue://emn2008, transactionId = null, expiration = 0, timestamp = 1213962132036,
arrival = 0, correlationId = null, replyTo = null, persistent = true, type = null, priority
= 4, groupID = null, groupSequence = 0, targetConsumerId = null, compressed = false, userID
= null, content = org.apache.activemq.util.ByteSequence@931b44, marshalledProperties = null,
dataStructure = null, redeliveryCounter = 0, size = 3239, properties = null, readOnlyProperties
= true, readOnlyBody = true, droppable = false, text = null}
2008-06-20 04:42:12,036 [758751348-3:1:2] DEBUG HttpConnection                 - An error
occurred while reading from the socket, is appears to be stale
java.net.SocketException: Socket Closed
	at java.net.PlainSocketImpl.setOption(Unknown Source)
	at java.net.Socket.setSoTimeout(Unknown Source)
	at com.sun.net.ssl.internal.ssl.SSLSocketImpl.setSoTimeout(Unknown Source)
	at org.apache.commons.httpclient.HttpConnection.isStale(HttpConnection.java:510)
	at org.apache.commons.httpclient.HttpConnection.isOpen(HttpConnection.java:436)
	at org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:626)
	at org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:497)
	at org.apache.activemq.transport.http.HttpClientTransport.oneway(HttpClientTransport.java:83)
	at org.apache.activemq.transport.failover.FailoverTransport.oneway(FailoverTransport.java:392)
	at org.apache.activemq.transport.MutexTransport.oneway(MutexTransport.java:47)
	at org.apache.activemq.transport.ResponseCorrelator.asyncRequest(ResponseCorrelator.java:69)
	at org.apache.activemq.transport.ResponseCorrelator.request(ResponseCorrelator.java:74)
	at org.apache.activemq.ActiveMQConnection.syncSendPacket(ActiveMQConnection.java:1175)
	at org.apache.activemq.ActiveMQSession.send(ActiveMQSession.java:1551)
	at org.apache.activemq.ActiveMQMessageProducer.send(ActiveMQMessageProducer.java:465)
	at org.apache.activemq.ActiveMQMessageProducer.send(ActiveMQMessageProducer.java:415)
	at org.apache.activemq.ActiveMQQueueSender.send(ActiveMQQueueSender.java:116)
	at org.apache.activemq.network.jms.QueueBridge.sendMessage(QueueBridge.java:87)
	at org.apache.activemq.network.jms.DestinationBridge.onMessage(DestinationBridge.java:134)
	at org.apache.activemq.ActiveMQMessageConsumer.dispatch(ActiveMQMessageConsumer.java:854)
	at org.apache.activemq.ActiveMQSessionExecutor.dispatch(ActiveMQSessionExecutor.java:99)
	at org.apache.activemq.ActiveMQSessionExecutor.iterate(ActiveMQSessionExecutor.java:166)
	at org.apache.activemq.thread.DedicatedTaskRunner.runTask(DedicatedTaskRunner.java:101)
	at org.apache.activemq.thread.DedicatedTaskRunner.access$000(DedicatedTaskRunner.java:25)
	at org.apache.activemq.thread.DedicatedTaskRunner$1.run(DedicatedTaskRunner.java:39)
2008-06-20 04:42:12,052 [758751348-3:1:2] DEBUG HttpConnection                 - Connection
is stale, closing...
2008-06-20 04:42:13,161 [758751348-3:1:2] DEBUG HttpConnection                 - HttpConnection.setSoTimeout(30000)
2008-06-20 04:42:13,161 [758751348-3:1:2] DEBUG HttpMethodBase                 - Execute loop
try 1
2008-06-20 04:42:13,161 [758751348-3:1:2] DEBUG header                         - >>
"POST /amq/ HTTP/1.1[\r][\n]"
2008-06-20 04:42:13,161 [758751348-3:1:2] DEBUG HttpMethodBase                 - Adding Host
request header
2008-06-20 04:42:13,161 [758751348-3:1:2] DEBUG header                         - >>
"clientID: ID:emn2008-82646A4E67-2374-1213758751348-5:5[\r][\n]"
2008-06-20 04:42:13,161 [758751348-3:1:2] DEBUG header                         - >>
"User-Agent: Jakarta Commons-HttpClient/2.0.1[\r][\n]"
2008-06-20 04:42:13,161 [758751348-3:1:2] DEBUG header                         - >>
"Host: publisher.emn2008.com[\r][\n]"
2008-06-20 04:42:13,161 [758751348-3:1:2] DEBUG header                         - >>
"Content-Length: 2950[\r][\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG header                         - >>
"[\r][\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG EntityEnclosingMethod          - Using buffered
request body
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"<org.apache.activemq.command.ActiveMQTextMessage>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"  <messageId>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"    <producerId>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"      <connectionId>ID:emn2008-82646A4E67-2374-1213758751348-3:0</connectionId>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"      <sessionId>2</sessionId>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"      <value>1</value>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"    </producerId>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"    <producerSequenceId>172</producerSequenceId>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"    <brokerSequenceId>0</brokerSequenceId>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"  </messageId>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"  <producerId reference="../messageId/producerId"/>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"  <destination class="org.apache.activemq.command.ActiveMQQueue">[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"    <string>emn2008</string>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"    <null/>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"  </destination>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"  <expiration>0</expiration>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"  <timestamp>1213962132036</timestamp>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"  <arrival>0</arrival>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"  <persistent>true</persistent>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"  <priority>4</priority>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"  <groupSequence>0</groupSequence>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"  <compressed>false</compressed>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"  <content>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"    <data>AAAFRzw/eG1sIHZlcnNpb249IjEuMCIgZW5jb2Rpbmc9IlVURi04IiA/PjxlbW44LW1lc3NhZ2Ug[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"eG1sbnM6eHNpPSJodHRwOi8vd3d3LnczLm9yZy8yMDAxL1hNTFNjaGVtYS1pbnN0YW5jZSIgeHNp[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"Om5vTmFtZXNwYWNlU2NoZW1hTG9jYXRpb249ImVtbjgtbWVzc2FnZS54c2QiIGJ1c2luZXNzRW50[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"aXR5SWQ9Ijg0OCIgYnVzaW5lc3NFbnRpdHlOYW1lPSJDYXJscyBKci4gKDY1MSkgS2lvc2sgMiIg[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"c3RvcmVOYnI9IjY1MSIgaXA9IjE5Mi4xNjguMS4xMiIgY3JlYXRpb25EYXRldGltZT0iMjAwOC0w[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"Ni0yMFQwNDoyNDo0MS43NTUtMDcwMCIgZXhwaXJhdGlvbkRhdGV0aW1lPSIyMDA4LTA2LTIwVDA1[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"OjI0OjM0Ljk1OC0wNzAwIj48a2lvc2tTdGF0dXMgY2xhc3M9Imtpb3NrLXN0YXR1cyIgYXZhaWxh[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"YmlsaXR5PSJPRkZMSU5FIiBtb2RlPSJPUkRFUklOR19NT0RFIiBzZXZlcml0eUxldmVsPSJMT1ci[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"IG1lc3NhZ2U9IlRoZSBhcHBsaWNhdGlvbiBpcyBvZmZsaW5lLiI+PC9raW9za1N0YXR1cz48Y29t[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"cG9uZW50U3RhdHVzTGlzdD48Y29tcG9uZW50LXN0YXR1cyBjb21wb25lbnROYW1lPSJPTkxJTkUi[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"IGF2YWlsYWJpbGl0eT0iT05MSU5FIiBtb2RlPSJSRUdVTEFSIiBzZXZlcml0eUxldmVsPSJMT1ci[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"IG1lc3NhZ2U9IlByaW50ZXIgTW9kZWwgb25saW5lLiI+PC9jb21wb25lbnQtc3RhdHVzPjxjb21w[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"b25lbnQtc3RhdHVzIGNvbXBvbmVudE5hbWU9IkNBUkRSRUFERVJPTkxJTkUiIGF2YWlsYWJpbGl0[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"eT0iT05MSU5FIiBtb2RlPSJPTkxJTkVfTU9ERSIgc2V2ZXJpdHlMZXZlbD0iTE9XIiBtZXNzYWdl[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"PSJDYXJkIFJlYWRlciBEZXZpY2Ugb25saW5lLiI+PC9jb21wb25lbnQtc3RhdHVzPjxjb21wb25l[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"bnQtc3RhdHVzIGNvbXBvbmVudE5hbWU9IkJJTExNQUNISU5FU09OTElORSIgYXZhaWxhYmlsaXR5[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"PSJPTkxJTkUiIG1vZGU9Ik9OTElORV9NT0RFIiBzZXZlcml0eUxldmVsPSJMT1ciIG1lc3NhZ2U9[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"IkJpbGwgTWFjaGluZXMgb25saW5lLiI+PC9jb21wb25lbnQtc3RhdHVzPjxjb21wb25lbnQtc3Rh[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"dHVzIGNvbXBvbmVudE5hbWU9IkNPSU5TT05MSU5FIiBhdmFpbGFiaWxpdHk9Ik9OTElORSIgbW9k[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"ZT0iT05MSU5FX01PREUiIHNldmVyaXR5TGV2ZWw9IkxPVyIgbWVzc2FnZT0iQ29pbnMgRGV2aWNl[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"IG9ubGluZS4iPjwvY29tcG9uZW50LXN0YXR1cz48Y29tcG9uZW50LXN0YXR1cyBjb21wb25lbnRO[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"YW1lPSJQT1NPTkxJTkUiIGF2YWlsYWJpbGl0eT0iT05MSU5FIiBtb2RlPSJPTkxJTkVfTU9ERSIg[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"c2V2ZXJpdHlMZXZlbD0iTE9XIiBtZXNzYWdlPSJQT1MgaXMgb25saW5lLiI+PC9jb21wb25lbnQt[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"c3RhdHVzPjwvY29tcG9uZW50U3RhdHVzTGlzdD48L2VtbjgtbWVzc2FnZT4=</data>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"    <offset>0</offset>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"    <length>1355</length>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"  </content>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"  <redeliveryCounter>0</redeliveryCounter>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"  <size>3239</size>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"  <readOnlyProperties>true</readOnlyProperties>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"  <readOnlyBody>true</readOnlyBody>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"  <droppable>false</droppable>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"  <commandId>178</commandId>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"  <responseRequired>true</responseRequired>[\n]"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG content                        - >>
"</org.apache.activemq.command.ActiveMQTextMessage>"
2008-06-20 04:42:13,270 [758751348-3:1:2] DEBUG EntityEnclosingMethod          - Request body
sent
2008-06-20 04:42:13,427 [758751348-3:1:2] DEBUG header                         - <<
"HTTP/1.1 200 OK[\r][\n]"
2008-06-20 04:42:13,427 [758751348-3:1:2] DEBUG header                         - <<
"Server: Apache-Coyote/1.1[\r][\n]"
2008-06-20 04:42:13,427 [758751348-3:1:2] DEBUG header                         - <<
"Content-Length: 0[\r][\n]"
2008-06-20 04:42:13,427 [758751348-3:1:2] DEBUG header                         - <<
"Date: Fri, 20 Jun 2008 11:40:16 GMT[\r][\n]"
2008-06-20 04:42:13,427 [758751348-3:1:2] DEBUG HttpMethodBase                 - Buffering
response body
2008-06-20 04:42:13,427 [758751348-3:1:2] DEBUG HttpMethodBase                 - Resorting
to protocol version default close connection policy
2008-06-20 04:42:13,427 [758751348-3:1:2] DEBUG HttpMethodBase                 - Should NOT
close connection, using HTTP/1.1.
2008-06-20 04:42:48,583 [Q Cleanup Timer] DEBUG JDBCPersistenceAdapter         - Cleaning
up old messages.
2008-06-20 04:42:48,583 [Q Cleanup Timer] DEBUG DefaultJDBCAdapter             - Executing
SQL: DELETE FROM ACTIVEMQ_MSGS WHERE ( EXPIRATION<>0 AND EXPIRATION<?) OR ID <=
( SELECT min(ACTIVEMQ_ACKS.LAST_ACKED_ID) FROM ACTIVEMQ_ACKS WHERE ACTIVEMQ_ACKS.CONTAINER=ACTIVEMQ_MSGS.CONTAINER)
2008-06-20 04:42:48,583 [Q Cleanup Timer] DEBUG DefaultJDBCAdapter             - Deleted 0
old message(s).
2008-06-20 04:42:48,583 [Q Cleanup Timer] DEBUG JDBCPersistenceAdapter         - Cleanup done.
2008-06-20 04:43:16,598 [eckpoint Worker] DEBUG JournalPersistenceAdapter      - Checkpoint
started.
2008-06-20 04:43:16,598 [eckpoint Worker] DEBUG JDBCPersistenceAdapter         - Cleaning
up old messages.
2008-06-20 04:43:16,598 [eckpoint Worker] DEBUG DefaultJDBCAdapter             - Executing
SQL: DELETE FROM ACTIVEMQ_MSGS WHERE ( EXPIRATION<>0 AND EXPIRATION<?) OR ID <=
( SELECT min(ACTIVEMQ_ACKS.LAST_ACKED_ID) FROM ACTIVEMQ_ACKS WHERE ACTIVEMQ_ACKS.CONTAINER=ACTIVEMQ_MSGS.CONTAINER)
2008-06-20 04:43:16,598 [eckpoint Worker] DEBUG DefaultJDBCAdapter             - Deleted 0
old message(s).
2008-06-20 04:43:16,598 [eckpoint Worker] DEBUG JDBCPersistenceAdapter         - Cleanup done.
2008-06-20 04:43:16,598 [eckpoint Worker] DEBUG JournalPersistenceAdapter      - Checkpoint
done.
2008-06-20 04:47:48,598 [Q Cleanup Timer] DEBUG JDBCPersistenceAdapter         - Cleaning
up old messages.
2008-06-20 04:47:48,598 [Q Cleanup Timer] DEBUG DefaultJDBCAdapter             - Executing
SQL: DELETE FROM ACTIVEMQ_MSGS WHERE ( EXPIRATION<>0 AND EXPIRATION<?) OR ID <=
( SELECT min(ACTIVEMQ_ACKS.LAST_ACKED_ID) FROM ACTIVEMQ_ACKS WHERE ACTIVEMQ_ACKS.CONTAINER=ACTIVEMQ_MSGS.CONTAINER)
2008-06-20 04:47:48,598 [Q Cleanup Timer] DEBUG DefaultJDBCAdapter             - Deleted 0
old message(s).
2008-06-20 04:47:48,598 [Q Cleanup Timer] DEBUG JDBCPersistenceAdapter         - Cleanup done.
2008-06-20 04:48:16,614 [eckpoint Worker] DEBUG JournalPersistenceAdapter      - Checkpoint
started.
2008-06-20 04:48:16,614 [eckpoint Worker] DEBUG JDBCPersistenceAdapter         - Cleaning
up old messages.
2008-06-20 04:48:16,614 [eckpoint Worker] DEBUG DefaultJDBCAdapter             - Executing
SQL: DELETE FROM ACTIVEMQ_MSGS WHERE ( EXPIRATION<>0 AND EXPIRATION<?) OR ID <=
( SELECT min(ACTIVEMQ_ACKS.LAST_ACKED_ID) FROM ACTIVEMQ_ACKS WHERE ACTIVEMQ_ACKS.CONTAINER=ACTIVEMQ_MSGS.CONTAINER)
2008-06-20 04:48:16,614 [eckpoint Worker] DEBUG DefaultJDBCAdapter             - Deleted 0
old message(s).
2008-06-20 04:48:16,614 [eckpoint Worker] DEBUG JDBCPersistenceAdapter         - Cleanup done.
2008-06-20 04:48:16,614 [eckpoint Worker] DEBUG JournalPersistenceAdapter      - Checkpoint
done.
2008-06-20 04:52:48,614 [Q Cleanup Timer] DEBUG JDBCPersistenceAdapter         - Cleaning
up old messages.
2008-06-20 04:52:48,614 [Q Cleanup Timer] DEBUG DefaultJDBCAdapter             - Executing
SQL: DELETE FROM ACTIVEMQ_MSGS WHERE ( EXPIRATION<>0 AND EXPIRATION<?) OR ID <=
( SELECT min(ACTIVEMQ_ACKS.LAST_ACKED_ID) FROM ACTIVEMQ_ACKS WHERE ACTIVEMQ_ACKS.CONTAINER=ACTIVEMQ_MSGS.CONTAINER)
2008-06-20 04:52:48,614 [Q Cleanup Timer] DEBUG DefaultJDBCAdapter             - Deleted 0
old message(s).
2008-06-20 04:52:48,614 [Q Cleanup Timer] DEBUG JDBCPersistenceAdapter         - Cleanup done.

Any information can help.


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


Mime
View raw message