activemq-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Edwin Yu (JIRA)" <j...@apache.org>
Subject [jira] [Commented] (AMQ-4051) IOException: Could not locate data file .\db-10.log
Date Tue, 19 Nov 2013 19:07:22 GMT

    [ https://issues.apache.org/jira/browse/AMQ-4051?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13826826#comment-13826826
] 

Edwin Yu commented on AMQ-4051:
-------------------------------

I just want to add new information.  After we upgraded to 5.8.0, we encountered the same error
one more time.  So the bug is still in 5.8.0.

2013-11-19 08:33:39,827 ERROR | ActiveMQ BrokerService[broker Task-3975 | [Queue:1494] Failed
to page in more queue messages 
java.lang.RuntimeException: java.lang.RuntimeException: java.io.IOException: Could not locate
data file .\esb\amq_perform77a\db-958.log
            at org.apache.activemq.broker.region.cursors.AbstractStoreCursor.reset(AbstractStoreCursor.java:113)
            at org.apache.activemq.broker.region.cursors.StoreQueueCursor.reset(StoreQueueCursor.java:157)
            at org.apache.activemq.broker.region.Queue.doPageInForDispatch(Queue.java:1775)
            at org.apache.activemq.broker.region.Queue.pageInMessages(Queue.java:2003)
            at org.apache.activemq.broker.region.Queue.iterate(Queue.java:1491)
            at org.apache.activemq.thread.PooledTaskRunner.runTask(PooledTaskRunner.java:129)
            at org.apache.activemq.thread.PooledTaskRunner$1.run(PooledTaskRunner.java:47)
            at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
            at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
            at java.lang.Thread.run(Thread.java:722)
Caused by: java.lang.RuntimeException: java.io.IOException: Could not locate data file .\esb\amq_n4-perform77a\db-958.log
            at org.apache.activemq.broker.region.cursors.AbstractStoreCursor.fillBatch(AbstractStoreCursor.java:277)
            at org.apache.activemq.broker.region.cursors.AbstractStoreCursor.reset(AbstractStoreCursor.java:110)
            ... 9 more
Caused by: java.io.IOException: Could not locate data file .\esb\amq_perform77a\db-958.log
            at org.apache.activemq.store.kahadb.disk.journal.Journal.getDataFile(Journal.java:353)
            at org.apache.activemq.store.kahadb.disk.journal.Journal.read(Journal.java:600)
            at org.apache.activemq.store.kahadb.MessageDatabase.load(MessageDatabase.java:961)
            at org.apache.activemq.store.kahadb.KahaDBStore.loadMessage(KahaDBStore.java:1029)
            at org.apache.activemq.store.kahadb.KahaDBStore$KahaDBMessageStore$4.execute(KahaDBStore.java:557)
            at org.apache.activemq.store.kahadb.disk.page.Transaction.execute(Transaction.java:779)
            at org.apache.activemq.store.kahadb.KahaDBStore$KahaDBMessageStore.recoverNextMessages(KahaDBStore.java:546)
            at org.apache.activemq.store.ProxyMessageStore.recoverNextMessages(ProxyMessageStore.java:106)
            at org.apache.activemq.broker.region.cursors.QueueStorePrefetch.doFillBatch(QueueStorePrefetch.java:97)
            at org.apache.activemq.broker.region.cursors.AbstractStoreCursor.fillBatch(AbstractStoreCursor.java:274)
            ... 10 more


> IOException: Could not locate data file .\db-10.log
> ---------------------------------------------------
>
>                 Key: AMQ-4051
>                 URL: https://issues.apache.org/jira/browse/AMQ-4051
>             Project: ActiveMQ
>          Issue Type: Bug
>          Components: Broker
>    Affects Versions: 5.6.0, 5.8.0
>         Environment: Windows Server 2008 R2, 8GB, Intel Core 2 Duo CPU 64 bit
> JDK 1.6.0_26, 64 bit
>            Reporter: Edwin Yu
>             Fix For: NEEDS_REVIEWED
>
>         Attachments: activemq.xml
>
>
> We're doing load testing on the ActiveMQ 5.6.0 broker-to-broker connection, pumping in
about 80,000 messages per hour into the queue.  One of the brokers stops dequeueing messages
to the consumer while its queue size increases.  The log contains repeating errors about "could
not locate the data file."
> We encountered this same issue couple weeks ago.  I found AMQ-3120 and added ignoreMissingJouralFiles="true"
like that issue suggested.  After many more days of testing, today we countered this same
problem again.  Our disk space is plenty.  The log is listed below.  I attached our activemq
configuration from the troubled broker to this issue.
> ------------------------------------------------------------------
> 2012-09-14 16:01:47,354 ERROR | BrokerService[xps-amq-broker_HPC-HAIFA-HAIFA-HAIFA] Task-18
| [Journal:352] Looking for key 10 but not found in fileMap: {11=db-11.log number = 11 , length
= 499163}
> 2012-09-14 16:01:47,354 ERROR | BrokerService[xps-amq-broker_HPC-HAIFA-HAIFA-HAIFA] Task-18
| [AbstractStoreCursor:279] org.apache.activemq.broker.region.cursors.QueueStorePrefetch@5fae7502:n4.HPC/HAIFA/HAIFA/HAIFA,batchResetNeeded=false,storeHasMessages=true,size=100,cacheEnabled=false
- Failed to fill batch
> java.io.IOException: Could not locate data file .\esb\amq_N4-PERFORM70\db-10.log
> 	at org.apache.kahadb.journal.Journal.getDataFile(Journal.java:353)
> 	at org.apache.kahadb.journal.Journal.read(Journal.java:600)
> 	at org.apache.activemq.store.kahadb.MessageDatabase.load(MessageDatabase.java:924)
> 	at org.apache.activemq.store.kahadb.KahaDBStore.loadMessage(KahaDBStore.java:1015)
> 	at org.apache.activemq.store.kahadb.KahaDBStore$KahaDBMessageStore$4.execute(KahaDBStore.java:556)
> 	at org.apache.kahadb.page.Transaction.execute(Transaction.java:769)
> 	at org.apache.activemq.store.kahadb.KahaDBStore$KahaDBMessageStore.recoverNextMessages(KahaDBStore.java:545)
> 	at org.apache.activemq.store.ProxyMessageStore.recoverNextMessages(ProxyMessageStore.java:106)
> 	at org.apache.activemq.broker.region.cursors.QueueStorePrefetch.doFillBatch(QueueStorePrefetch.java:97)
> 	at org.apache.activemq.broker.region.cursors.AbstractStoreCursor.fillBatch(AbstractStoreCursor.java:277)
> 	at org.apache.activemq.broker.region.cursors.AbstractStoreCursor.reset(AbstractStoreCursor.java:113)
> 	at org.apache.activemq.broker.region.cursors.StoreQueueCursor.reset(StoreQueueCursor.java:157)
> 	at org.apache.activemq.broker.region.Queue.doPageInForDispatch(Queue.java:1766)
> 	at org.apache.activemq.broker.region.Queue.pageInMessages(Queue.java:1995)
> 	at org.apache.activemq.broker.region.Queue.iterate(Queue.java:1488)
> 	at org.apache.activemq.thread.PooledTaskRunner.runTask(PooledTaskRunner.java:122)
> 	at org.apache.activemq.thread.PooledTaskRunner$1.run(PooledTaskRunner.java:43)
> 	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:662)
> 2012-09-14 16:01:47,354 ERROR | BrokerService[xps-amq-broker_HPC-HAIFA-HAIFA-HAIFA] Task-18
| [AbstractStoreCursor:115] org.apache.activemq.broker.region.cursors.QueueStorePrefetch@5fae7502:n4.HPC/HAIFA/HAIFA/HAIFA,batchResetNeeded=false,storeHasMessages=true,size=100,cacheEnabled=false
- Failed to fill batch
> java.lang.RuntimeException: java.io.IOException: Could not locate data file .\esb\amq_N4-PERFORM70\db-10.log
> 	at org.apache.activemq.broker.region.cursors.AbstractStoreCursor.fillBatch(AbstractStoreCursor.java:280)
> 	at org.apache.activemq.broker.region.cursors.AbstractStoreCursor.reset(AbstractStoreCursor.java:113)
> 	at org.apache.activemq.broker.region.cursors.StoreQueueCursor.reset(StoreQueueCursor.java:157)
> 	at org.apache.activemq.broker.region.Queue.doPageInForDispatch(Queue.java:1766)
> 	at org.apache.activemq.broker.region.Queue.pageInMessages(Queue.java:1995)
> 	at org.apache.activemq.broker.region.Queue.iterate(Queue.java:1488)
> 	at org.apache.activemq.thread.PooledTaskRunner.runTask(PooledTaskRunner.java:122)
> 	at org.apache.activemq.thread.PooledTaskRunner$1.run(PooledTaskRunner.java:43)
> 	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:662)
> Caused by: java.io.IOException: Could not locate data file .\esb\amq_N4-PERFORM70\db-10.log
> 	at org.apache.kahadb.journal.Journal.getDataFile(Journal.java:353)
> 	at org.apache.kahadb.journal.Journal.read(Journal.java:600)
> 	at org.apache.activemq.store.kahadb.MessageDatabase.load(MessageDatabase.java:924)
> 	at org.apache.activemq.store.kahadb.KahaDBStore.loadMessage(KahaDBStore.java:1015)
> 	at org.apache.activemq.store.kahadb.KahaDBStore$KahaDBMessageStore$4.execute(KahaDBStore.java:556)
> 	at org.apache.kahadb.page.Transaction.execute(Transaction.java:769)
> 	at org.apache.activemq.store.kahadb.KahaDBStore$KahaDBMessageStore.recoverNextMessages(KahaDBStore.java:545)
> 	at org.apache.activemq.store.ProxyMessageStore.recoverNextMessages(ProxyMessageStore.java:106)
> 	at org.apache.activemq.broker.region.cursors.QueueStorePrefetch.doFillBatch(QueueStorePrefetch.java:97)
> 	at org.apache.activemq.broker.region.cursors.AbstractStoreCursor.fillBatch(AbstractStoreCursor.java:277)
> 	... 10 more
> 2012-09-14 16:01:47,354 ERROR | BrokerService[xps-amq-broker_HPC-HAIFA-HAIFA-HAIFA] Task-18
| [Queue:1491] Failed to page in more queue messages 
> java.lang.RuntimeException: java.lang.RuntimeException: java.io.IOException: Could not
locate data file .\esb\amq_N4-PERFORM70\db-10.log
> 	at org.apache.activemq.broker.region.cursors.AbstractStoreCursor.reset(AbstractStoreCursor.java:116)
> 	at org.apache.activemq.broker.region.cursors.StoreQueueCursor.reset(StoreQueueCursor.java:157)
> 	at org.apache.activemq.broker.region.Queue.doPageInForDispatch(Queue.java:1766)
> 	at org.apache.activemq.broker.region.Queue.pageInMessages(Queue.java:1995)
> 	at org.apache.activemq.broker.region.Queue.iterate(Queue.java:1488)
> 	at org.apache.activemq.thread.PooledTaskRunner.runTask(PooledTaskRunner.java:122)
> 	at org.apache.activemq.thread.PooledTaskRunner$1.run(PooledTaskRunner.java:43)
> 	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:662)
> Caused by: java.lang.RuntimeException: java.io.IOException: Could not locate data file
.\esb\amq_N4-PERFORM70\db-10.log
> 	at org.apache.activemq.broker.region.cursors.AbstractStoreCursor.fillBatch(AbstractStoreCursor.java:280)
> 	at org.apache.activemq.broker.region.cursors.AbstractStoreCursor.reset(AbstractStoreCursor.java:113)
> 	... 9 more
> Caused by: java.io.IOException: Could not locate data file .\esb\amq_N4-PERFORM70\db-10.log
> 	at org.apache.kahadb.journal.Journal.getDataFile(Journal.java:353)
> 	at org.apache.kahadb.journal.Journal.read(Journal.java:600)
> 	at org.apache.activemq.store.kahadb.MessageDatabase.load(MessageDatabase.java:924)
> 	at org.apache.activemq.store.kahadb.KahaDBStore.loadMessage(KahaDBStore.java:1015)
> 	at org.apache.activemq.store.kahadb.KahaDBStore$KahaDBMessageStore$4.execute(KahaDBStore.java:556)
> 	at org.apache.kahadb.page.Transaction.execute(Transaction.java:769)
> 	at org.apache.activemq.store.kahadb.KahaDBStore$KahaDBMessageStore.recoverNextMessages(KahaDBStore.java:545)
> 	at org.apache.activemq.store.ProxyMessageStore.recoverNextMessages(ProxyMessageStore.java:106)
> 	at org.apache.activemq.broker.region.cursors.QueueStorePrefetch.doFillBatch(QueueStorePrefetch.java:97)
> 	at org.apache.activemq.broker.region.cursors.AbstractStoreCursor.fillBatch(AbstractStoreCursor.java:277)
> 	... 10 more
> 2012-09-14 16:01:47,354 INFO  | ActiveMQ Session Task-26 | [LogComponent:41] 
> --------------------------------------------------------------------------



--
This message was sent by Atlassian JIRA
(v6.1#6144)

Mime
View raw message