You are viewing a plain text version of this content. The canonical link for it is here.
Posted to users@activemq.apache.org by James Green <ja...@gmail.com> on 2012/10/25 18:31:27 UTC

kahadb repeatedly recovering from journal (activemq 5.7.0)

Each time we start this broker we're getting:

2012-10-25 17:21:51,083 | INFO  | Refreshing
org.apache.activemq.xbean.XBeanBrokerFactory$1@bc5fde0: startup date [Thu
Oct 25 17:21:51 BST 2012]; root of context hierarchy |
org.apache.activemq.xbean.XBeanBrokerFactory$1 | main
2012-10-25 17:21:52,492 | INFO  |
PListStore:[/opt/activemq/data/zorin/tmp_storage] started |
org.apache.activemq.store.kahadb.plist.PListStore | main
2012-10-25 17:21:52,532 | INFO  | Using Persistence Adapter:
KahaDBPersistenceAdapter[/opt/activemq/data/kahadb] |
org.apache.activemq.broker.BrokerService | main
2012-10-25 17:21:52,946 | INFO  | KahaDB is version 4 |
org.apache.activemq.store.kahadb.MessageDatabase | main
2012-10-25 17:21:53,061 | WARN  | Index corrupted. Recovering the index
through journal replay. Cause:java.io.EOFException |
org.apache.activemq.store.kahadb.MessageDatabase | main
2012-10-25 17:21:53,432 | INFO  | Recovering from the journal ... |
org.apache.activemq.store.kahadb.MessageDatabase | main
2012-10-25 17:22:06,585 | INFO  | @5737:2701470, 100000 entries recovered
.. | org.apache.activemq.store.kahadb.MessageDatabase | main
2012-10-25 17:23:00,531 | INFO  | @5739:16482933, 200000 entries recovered
.. | org.apache.activemq.store.kahadb.MessageDatabase | main
2012-10-25 17:23:45,282 | INFO  | @5741:28057520, 300000 entries recovered
.. | org.apache.activemq.store.kahadb.MessageDatabase | main
2012-10-25 17:24:12,952 | INFO  | @5744:6763324, 400000 entries recovered
.. | org.apache.activemq.store.kahadb.MessageDatabase | main
2012-10-25 17:24:26,218 | INFO  | @5746:23527767, 500000 entries recovered
.. | org.apache.activemq.store.kahadb.MessageDatabase | main
2012-10-25 17:24:40,783 | INFO  | @5748:27554440, 600000 entries recovered
.. | org.apache.activemq.store.kahadb.MessageDatabase | main
2012-10-25 17:25:02,656 | INFO  | @5751:29768691, 700000 entries recovered
.. | org.apache.activemq.store.kahadb.MessageDatabase | main
2012-10-25 17:25:32,674 | INFO  | @5755:8124925, 800000 entries recovered
.. | org.apache.activemq.store.kahadb.MessageDatabase | main
2012-10-25 17:26:24,690 | INFO  | @5757:24949086, 900000 entries recovered
.. | org.apache.activemq.store.kahadb.MessageDatabase | main

etc 20 minutes later we're online again.

java version "1.6.0_20"
OpenJDK Runtime Environment (IcedTea6 1.9.2) (6b20-1.9.2-0ubuntu1)
OpenJDK 64-Bit Server VM (build 19.0-b09, mixed mode)

This is after doing an /etc/init.d/activemq stop (which just points to the
distributed init script).

Any ideas?

Thanks,

James

Re: kahadb repeatedly recovering from journal (activemq 5.7.0)

Posted by Gary Tully <ga...@gmail.com>.
enable debug logging of
org.apache.activemq.store.kahadb.MessageDatabase and you will get a
full stack trace to the source of the :java.io.EOFException

That will give us a hint as to where the problem is.


On 25 October 2012 17:31, James Green <ja...@gmail.com> wrote:
> Each time we start this broker we're getting:
>
> 2012-10-25 17:21:51,083 | INFO  | Refreshing
> org.apache.activemq.xbean.XBeanBrokerFactory$1@bc5fde0: startup date [Thu
> Oct 25 17:21:51 BST 2012]; root of context hierarchy |
> org.apache.activemq.xbean.XBeanBrokerFactory$1 | main
> 2012-10-25 17:21:52,492 | INFO  |
> PListStore:[/opt/activemq/data/zorin/tmp_storage] started |
> org.apache.activemq.store.kahadb.plist.PListStore | main
> 2012-10-25 17:21:52,532 | INFO  | Using Persistence Adapter:
> KahaDBPersistenceAdapter[/opt/activemq/data/kahadb] |
> org.apache.activemq.broker.BrokerService | main
> 2012-10-25 17:21:52,946 | INFO  | KahaDB is version 4 |
> org.apache.activemq.store.kahadb.MessageDatabase | main
> 2012-10-25 17:21:53,061 | WARN  | Index corrupted. Recovering the index
> through journal replay. Cause:java.io.EOFException |
> org.apache.activemq.store.kahadb.MessageDatabase | main
> 2012-10-25 17:21:53,432 | INFO  | Recovering from the journal ... |
> org.apache.activemq.store.kahadb.MessageDatabase | main
> 2012-10-25 17:22:06,585 | INFO  | @5737:2701470, 100000 entries recovered
> .. | org.apache.activemq.store.kahadb.MessageDatabase | main
> 2012-10-25 17:23:00,531 | INFO  | @5739:16482933, 200000 entries recovered
> .. | org.apache.activemq.store.kahadb.MessageDatabase | main
> 2012-10-25 17:23:45,282 | INFO  | @5741:28057520, 300000 entries recovered
> .. | org.apache.activemq.store.kahadb.MessageDatabase | main
> 2012-10-25 17:24:12,952 | INFO  | @5744:6763324, 400000 entries recovered
> .. | org.apache.activemq.store.kahadb.MessageDatabase | main
> 2012-10-25 17:24:26,218 | INFO  | @5746:23527767, 500000 entries recovered
> .. | org.apache.activemq.store.kahadb.MessageDatabase | main
> 2012-10-25 17:24:40,783 | INFO  | @5748:27554440, 600000 entries recovered
> .. | org.apache.activemq.store.kahadb.MessageDatabase | main
> 2012-10-25 17:25:02,656 | INFO  | @5751:29768691, 700000 entries recovered
> .. | org.apache.activemq.store.kahadb.MessageDatabase | main
> 2012-10-25 17:25:32,674 | INFO  | @5755:8124925, 800000 entries recovered
> .. | org.apache.activemq.store.kahadb.MessageDatabase | main
> 2012-10-25 17:26:24,690 | INFO  | @5757:24949086, 900000 entries recovered
> .. | org.apache.activemq.store.kahadb.MessageDatabase | main
>
> etc 20 minutes later we're online again.
>
> java version "1.6.0_20"
> OpenJDK Runtime Environment (IcedTea6 1.9.2) (6b20-1.9.2-0ubuntu1)
> OpenJDK 64-Bit Server VM (build 19.0-b09, mixed mode)
>
> This is after doing an /etc/init.d/activemq stop (which just points to the
> distributed init script).
>
> Any ideas?
>
> Thanks,
>
> James



-- 
http://redhat.com
http://blog.garytully.com