activemq-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Timothy Bish (JIRA)" <j...@apache.org>
Subject [jira] [Closed] (AMQ-1821) Https JMSBridgeConnector failed to recover due to an infinite loop in Journal cleanup.
Date Mon, 15 Aug 2011 22:37:27 GMT

     [ https://issues.apache.org/jira/browse/AMQ-1821?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]

Timothy Bish closed AMQ-1821.
-----------------------------

    Resolution: Fixed

4.x is EOL

> Https JMSBridgeConnector failed to recover due to an infinite loop in Journal cleanup.
> --------------------------------------------------------------------------------------
>
>                 Key: AMQ-1821
>                 URL: https://issues.apache.org/jira/browse/AMQ-1821
>             Project: ActiveMQ
>          Issue Type: Bug
>          Components: Broker
>    Affects Versions: 4.1.1
>         Environment: Win XP
>            Reporter: Hans
>            Priority: Critical
>             Fix For: NEEDS_REVIEWED
>
>         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.
For more information on JIRA, see: http://www.atlassian.com/software/jira

       

Mime
View raw message