Mailing-List: contact dev-help@activemq.apache.org; run by ezmlm
Precedence: bulk
Reply-To: dev@activemq.apache.org
Date: Mon, 5 Nov 2012 13:54:31 +0000 (UTC)
From: "Manfred Koch (JIRA)" <jira@apache.org>
To: dev@activemq.apache.org
Message-ID: <1190976537.68785.1352123671598.JavaMail.jiratomcat@arcas>
In-Reply-To: <1092232992.73520.1341006404379.JavaMail.jiratomcat@issues-vm>
Subject: [jira] [Commented] (AMQ-3906) repeated error message regarding
 chunk stream logged
MIME-Version: 1.0
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: 7bit


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

Manfred Koch commented on AMQ-3906:
-----------------------------------

We are using 5.7.0 on Windows 2008 R2 SP1 and have the same issue.
{noformat}
INFO   | jvm 1    | 2012/11/02 04:48:58 | 04:48:58,808[ActiveMQ Durable Subscriber Cleanup Timer]  INFO TopicRegion:296 - Destroying durable subscriber due to inactivity: DurableTopicSubscription-149f13f3-b548-4fc6-b343-3f5da174491d:0b071e2a-f281-4ce8-8a64-0b601d00c672, id=ID:host21-1354-1351827790937-1:1:3:1, active=false, destinations=1, total=0, pending=0, dispatched=0, inflight=0, prefetchExtension=0
INFO   | jvm 1    | 2012/11/02 04:48:58 | 04:48:58,808[ActiveMQ Durable Subscriber Cleanup Timer]  INFO TopicRegion:296 - Destroying durable subscriber due to inactivity: DurableTopicSubscription-149f13f3-b548-4fc6-b343-3f5da174491d:714ca25f-7ba8-420e-8e3f-6a4dff7c494c, id=ID:host21-1354-1351827790937-1:1:4:1, active=false, destinations=1, total=0, pending=0, dispatched=0, inflight=0, prefetchExtension=0
INFO   | jvm 1    | 2012/11/02 04:48:58 | 04:48:58,824[ActiveMQ Durable Subscriber Cleanup Timer]  INFO TopicRegion:296 - Destroying durable subscriber due to inactivity: DurableTopicSubscription-149f13f3-b548-4fc6-b343-3f5da174491d:09c8415f-ef48-45f3-a9db-a815498664d4, id=ID:host21-1354-1351827790937-1:1:7:1, active=false, destinations=1, total=0, pending=0, dispatched=0, inflight=0, prefetchExtension=0
INFO   | jvm 1    | 2012/11/02 05:06:36 | 05:06:36,590[ActiveMQ Broker[localhost] Scheduler] ERROR MessageDatabase:419 - KahaDB failed to store to Journal
INFO   | jvm 1    | 2012/11/02 05:06:36 | java.io.EOFException: Chunk stream does not exist, page: 3080 is marked free
INFO   | jvm 1    | 2012/11/02 05:06:36 | 	at org.apache.kahadb.page.Transaction$2.readPage(Transaction.java:460)
INFO   | jvm 1    | 2012/11/02 05:06:36 | 	at org.apache.kahadb.page.Transaction$2.<init>(Transaction.java:437)
{noformat}
                
> repeated error message regarding chunk stream logged
> ----------------------------------------------------
>
>                 Key: AMQ-3906
>                 URL: https://issues.apache.org/jira/browse/AMQ-3906
>             Project: ActiveMQ
>          Issue Type: Bug
>          Components: Broker
>    Affects Versions: 5.6.0
>         Environment: ActiveMQ 5.6.0 running on Linux FC10 x86
>            Reporter: Rajib Rashid
>         Attachments: activemq.xml
>
>
> after running normally for ~24 hours, warning messages/errors like below are logged every 30 seconds:
> {code}
> 2012-06-27 14:33:31,532 org.apache.activemq.broker.region.Topic[ActiveMQ Broker[ZyrionMessageBus] Scheduler]: (WARN) Failed to browse Topic: remoteUpdateP2PTopic
> java.io.EOFException: Chunk stream does not exist, page: 50 is marked free
>         at org.apache.kahadb.page.Transaction$2.readPage(Transaction.java:460)
>         at org.apache.kahadb.page.Transaction$2.<init>(Transaction.java:437)
>         at org.apache.kahadb.page.Transaction.openInputStream(Transaction.java:434)
>         at org.apache.kahadb.page.Transaction.load(Transaction.java:410)
>         at org.apache.kahadb.page.Transaction.load(Transaction.java:367)
>         at org.apache.kahadb.index.BTreeIndex.loadNode(BTreeIndex.java:262)
>         at org.apache.kahadb.index.BTreeIndex.getRoot(BTreeIndex.java:174)
>         at org.apache.kahadb.index.BTreeIndex.iterator(BTreeIndex.java:232)
>         at org.apache.activemq.store.kahadb.MessageDatabase$MessageOrderIndex$MessageOrderIterator.<init>(MessageDatabase.java:2714)
>         at org.apache.activemq.store.kahadb.MessageDatabase$MessageOrderIndex.iterator(MessageDatabase.java:2696)
>         at org.apache.activemq.store.kahadb.KahaDBStore$KahaDBMessageStore$3.execute(KahaDBStore.java:525)
>         at org.apache.kahadb.page.Transaction.execute(Transaction.java:769)
>         at org.apache.activemq.store.kahadb.KahaDBStore$KahaDBMessageStore.recover(KahaDBStore.java:521)
>         at org.apache.activemq.store.ProxyTopicMessageStore.recover(ProxyTopicMessageStore.java:62)
>         at org.apache.activemq.broker.region.Topic.doBrowse(Topic.java:559)
>         at org.apache.activemq.broker.region.Topic.access$100(Topic.java:62)
>         at org.apache.activemq.broker.region.Topic$6.run(Topic.java:684)
>         at org.apache.activemq.thread.SchedulerTimerTask.run(SchedulerTimerTask.java:33)
>         at java.util.TimerThread.mainLoop(Timer.java:512)
>         at java.util.TimerThread.run(Timer.java:462)
> {code}
> since then the warning has been logged 6000+ times. not sure if this is due to the fact that we have enabled expiration of queued messages for offline subscribers.
> {code}
> % ls -l apps/activemq/data/kahadb/
> total 32068
> -rw-r--r-- 1 root root 33030144 2012-06-29 14:44 db-14.log
> -rw-r--r-- 1 root root   339968 2012-06-29 14:44 db.data
> -rw-r--r-- 1 root root   196984 2012-06-29 14:44 db.redo
> -rw-r--r-- 1 root root        0 2012-06-26 16:40 lock
> {code}

--
This message is automatically generated by JIRA.
If you think it was sent incorrectly, please contact your JIRA administrators
For more information on JIRA, see: http://www.atlassian.com/software/jira