directory-users mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Slavomir Kocka <slavomir.ko...@gmail.com>
Subject Re: apacheds @ 100% permanently
Date Tue, 09 Jul 2013 09:41:14 GMT
Hi,

There is cluster of 4 servers writing intensively to LDAP. But even ,when there is no write, there is 100%...
However I can wait to end of the tests, to try to make thread dump then, if it is needed...

after kill -3 I found this in wrapper.log:

INFO   | jvm 1    | 2013/07/09 11:38:16 | Full thread dump Java HotSpot(TM) 64-Bit Server VM (20.45-b01 mixed mode):
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "pool-6-thread-49" prio=10 tid=0x00002aaab3645800 nid=0x1cc4 waiting for monitor entry [0x0000000043790000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: BLOCKED (on object monitor)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at jdbm.btree.BTree.find(BTree.java:400)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- waiting to lock <0x0000000705b0dc38> (a jdbm.btree.BTree)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable.get(JdbmTable.java:325)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1124)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1085)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.xdbm.search.evaluator.SubtreeScopeEvaluator.evaluate(SubtreeScopeEvaluator.java:169)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.xdbm.search.evaluator.AndEvaluator.evaluate(AndEvaluator.java:110)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:169)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:45)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.api.filtering.BaseEntryFilteringCursor.next(BaseEntryFilteringCursor.java:379)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.writeResults(SearchRequestHandler.java:391)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.doSimpleSearch(SearchRequestHandler.java:845)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleIgnoringReferrals(SearchRequestHandler.java:1153)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:206)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:92)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:206)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:55)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:221)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:217)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:690)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:417)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:47)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:765)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:474)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:428)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "pool-6-thread-48" prio=10 tid=0x00002aaab2cce000 nid=0x1cc3 waiting for monitor entry [0x000000004368f000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: BLOCKED (on object monitor)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at jdbm.btree.BTree.find(BTree.java:400)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- waiting to lock <0x0000000705b0dc38> (a jdbm.btree.BTree)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable.get(JdbmTable.java:325)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1124)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1085)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.xdbm.search.evaluator.SubtreeScopeEvaluator.evaluate(SubtreeScopeEvaluator.java:169)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.xdbm.search.evaluator.AndEvaluator.evaluate(AndEvaluator.java:110)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:169)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:45)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.api.filtering.BaseEntryFilteringCursor.next(BaseEntryFilteringCursor.java:379)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.writeResults(SearchRequestHandler.java:391)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.doSimpleSearch(SearchRequestHandler.java:845)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleIgnoringReferrals(SearchRequestHandler.java:1153)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:206)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:92)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:206)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:55)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:221)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:217)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:690)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:417)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:47)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:765)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:474)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:428)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "pool-6-thread-47" prio=10 tid=0x00002aaab2ccd800 nid=0x1cc0 waiting for monitor entry [0x000000004021c000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: BLOCKED (on object monitor)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at jdbm.btree.BTree.find(BTree.java:400)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- waiting to lock <0x0000000705b0dc38> (a jdbm.btree.BTree)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable.get(JdbmTable.java:325)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1124)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1085)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.xdbm.search.evaluator.SubtreeScopeEvaluator.evaluate(SubtreeScopeEvaluator.java:169)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.xdbm.search.evaluator.AndEvaluator.evaluate(AndEvaluator.java:110)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:169)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:45)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.api.filtering.BaseEntryFilteringCursor.next(BaseEntryFilteringCursor.java:379)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.writeResults(SearchRequestHandler.java:391)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.doSimpleSearch(SearchRequestHandler.java:845)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleIgnoringReferrals(SearchRequestHandler.java:1153)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:206)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:92)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:206)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:55)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:221)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:217)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:690)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:417)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:47)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:765)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:474)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:428)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "pool-6-thread-46" prio=10 tid=0x00002aaab3594800 nid=0x1b7c waiting for monitor entry [0x000000004348d000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: BLOCKED (on object monitor)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at jdbm.btree.BTree.find(BTree.java:400)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- waiting to lock <0x0000000705b0dc38> (a jdbm.btree.BTree)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable.get(JdbmTable.java:325)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1124)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1085)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.xdbm.search.evaluator.SubtreeScopeEvaluator.evaluate(SubtreeScopeEvaluator.java:169)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.xdbm.search.evaluator.AndEvaluator.evaluate(AndEvaluator.java:110)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:169)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:45)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.api.filtering.BaseEntryFilteringCursor.next(BaseEntryFilteringCursor.java:379)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.writeResults(SearchRequestHandler.java:391)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.doSimpleSearch(SearchRequestHandler.java:845)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleIgnoringReferrals(SearchRequestHandler.java:1153)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:206)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:92)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:206)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:55)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:221)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:217)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:690)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:417)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:47)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:765)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:474)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:428)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "pool-6-thread-45" prio=10 tid=0x00002aaab3594000 nid=0x1b6c waiting for monitor entry [0x0000000042e87000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: BLOCKED (on object monitor)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at jdbm.btree.BTree.find(BTree.java:400)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- waiting to lock <0x0000000705b0dc38> (a jdbm.btree.BTree)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable.get(JdbmTable.java:325)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1124)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1085)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.xdbm.search.evaluator.SubtreeScopeEvaluator.evaluate(SubtreeScopeEvaluator.java:169)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.xdbm.search.evaluator.AndEvaluator.evaluate(AndEvaluator.java:110)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:169)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:45)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.api.filtering.BaseEntryFilteringCursor.next(BaseEntryFilteringCursor.java:379)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.writeResults(SearchRequestHandler.java:391)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.doSimpleSearch(SearchRequestHandler.java:845)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleIgnoringReferrals(SearchRequestHandler.java:1153)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:206)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:92)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:206)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:55)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:221)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:217)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:690)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:417)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:47)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:765)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:474)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:428)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "pool-6-thread-44" prio=10 tid=0x00002aaab3052000 nid=0x1b69 waiting for monitor entry [0x000000004328b000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: BLOCKED (on object monitor)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at jdbm.btree.BTree.find(BTree.java:400)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- waiting to lock <0x0000000705b0dc38> (a jdbm.btree.BTree)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable.get(JdbmTable.java:325)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1124)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1085)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.xdbm.search.evaluator.SubtreeScopeEvaluator.evaluate(SubtreeScopeEvaluator.java:169)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.xdbm.search.evaluator.AndEvaluator.evaluate(AndEvaluator.java:110)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:169)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:45)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.api.filtering.BaseEntryFilteringCursor.next(BaseEntryFilteringCursor.java:379)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.writeResults(SearchRequestHandler.java:391)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.doSimpleSearch(SearchRequestHandler.java:845)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleIgnoringReferrals(SearchRequestHandler.java:1153)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:206)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:92)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:206)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:55)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:221)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:217)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:690)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:417)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:47)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:765)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:474)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:428)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "pool-6-thread-43" prio=10 tid=0x00002aaab3051800 nid=0x1b21 waiting for monitor entry [0x000000004338c000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: BLOCKED (on object monitor)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at jdbm.btree.BTree.find(BTree.java:400)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- waiting to lock <0x0000000705b0dc38> (a jdbm.btree.BTree)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable.get(JdbmTable.java:325)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1124)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1085)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.xdbm.search.evaluator.SubtreeScopeEvaluator.evaluate(SubtreeScopeEvaluator.java:169)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.xdbm.search.evaluator.AndEvaluator.evaluate(AndEvaluator.java:110)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:169)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:45)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.api.filtering.BaseEntryFilteringCursor.next(BaseEntryFilteringCursor.java:379)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.writeResults(SearchRequestHandler.java:391)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.doSimpleSearch(SearchRequestHandler.java:845)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleIgnoringReferrals(SearchRequestHandler.java:1153)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:206)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:92)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:206)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:55)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:221)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:217)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:690)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:417)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:47)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:765)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:474)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:428)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "NioProcessor-11" prio=10 tid=0x00002aaab4042800 nid=0x1b1f runnable [0x0000000042d86000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: RUNNABLE
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:210)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:65)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x000000070794ee20> (a sun.nio.ch.Util$2)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x000000070794ee10> (a java.util.Collections$UnmodifiableSet)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x000000070794ec08> (a sun.nio.ch.EPollSelectorImpl)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.transport.socket.nio.NioProcessor.select(NioProcessor.java:72)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:1077)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "NioProcessor-10" prio=10 tid=0x00002aaab4465800 nid=0x1b1b runnable [0x000000004358e000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: RUNNABLE
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:210)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:65)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x0000000707964e38> (a sun.nio.ch.Util$2)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x0000000707964e28> (a java.util.Collections$UnmodifiableSet)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x0000000707964c20> (a sun.nio.ch.EPollSelectorImpl)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.transport.socket.nio.NioProcessor.select(NioProcessor.java:72)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:1077)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "NioProcessor-6" prio=10 tid=0x00002aaab4041000 nid=0x1acf runnable [0x00000000415c3000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: RUNNABLE
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:210)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:65)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x0000000707965e10> (a sun.nio.ch.Util$2)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x0000000707965e00> (a java.util.Collections$UnmodifiableSet)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x0000000707965bc8> (a sun.nio.ch.EPollSelectorImpl)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.transport.socket.nio.NioProcessor.select(NioProcessor.java:72)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:1077)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "pool-6-thread-42" prio=10 tid=0x00002aaab3054800 nid=0x1acd runnable [0x0000000043189000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: RUNNABLE
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.LinkedList.access$200(LinkedList.java:74)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.LinkedList$ListItr.remove(LinkedList.java:732)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at jdbm.recman.RecordFile.get(RecordFile.java:176)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at jdbm.recman.PageManager.getNext(PageManager.java:237)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at jdbm.recman.PageCursor.next(PageCursor.java:104)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at jdbm.recman.PhysicalRowIdManager.fetch(PhysicalRowIdManager.java:159)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at jdbm.recman.BaseRecordManager.fetch(BaseRecordManager.java:323)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x0000000705b01960> (a jdbm.recman.BaseRecordManager)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at jdbm.recman.CacheRecordManager.fetch(CacheRecordManager.java:264)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x0000000705b01948> (a jdbm.recman.CacheRecordManager)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at jdbm.btree.BPage.loadBPage(BPage.java:949)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at jdbm.btree.BPage.find(BPage.java:280)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at jdbm.btree.BTree.find(BTree.java:413)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x0000000705b0dc38> (a jdbm.btree.BTree)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable.get(JdbmTable.java:325)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1124)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1085)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.xdbm.search.evaluator.SubtreeScopeEvaluator.evaluate(SubtreeScopeEvaluator.java:169)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.xdbm.search.evaluator.AndEvaluator.evaluate(AndEvaluator.java:110)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:169)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:45)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.core.api.filtering.BaseEntryFilteringCursor.next(BaseEntryFilteringCursor.java:379)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.writeResults(SearchRequestHandler.java:391)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.doSimpleSearch(SearchRequestHandler.java:845)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleIgnoringReferrals(SearchRequestHandler.java:1153)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:206)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:92)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:206)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:55)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:221)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:217)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:690)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:417)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:47)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:765)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:474)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:428)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "NioProcessor-7" prio=10 tid=0x00002aaab42d8000 nid=0x660f runnable [0x0000000043089000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: RUNNABLE
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:210)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:65)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x0000000707966688> (a sun.nio.ch.Util$2)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x0000000707966678> (a java.util.Collections$UnmodifiableSet)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x000000070794e5e0> (a sun.nio.ch.EPollSelectorImpl)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.transport.socket.nio.NioProcessor.select(NioProcessor.java:72)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:1077)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "NioProcessor-8" prio=10 tid=0x00002aaab403e000 nid=0x660d runnable [0x000000004247d000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: RUNNABLE
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:210)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:65)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x00000007079658a8> (a sun.nio.ch.Util$2)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x0000000707965898> (a java.util.Collections$UnmodifiableSet)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x0000000707965690> (a sun.nio.ch.EPollSelectorImpl)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.transport.socket.nio.NioProcessor.select(NioProcessor.java:72)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:1077)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "NioProcessor-9" prio=10 tid=0x00002aaab403d000 nid=0x6609 runnable [0x000000004227b000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: RUNNABLE
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:210)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:65)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x0000000707965370> (a sun.nio.ch.Util$2)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x0000000707965360> (a java.util.Collections$UnmodifiableSet)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x0000000707965158> (a sun.nio.ch.EPollSelectorImpl)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.transport.socket.nio.NioProcessor.select(NioProcessor.java:72)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:1077)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "NioProcessor-5" prio=10 tid=0x00002aaab404e000 nid=0x6606 runnable [0x0000000042c85000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: RUNNABLE
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:210)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:65)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x000000070794e3a0> (a sun.nio.ch.Util$2)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x000000070794e390> (a java.util.Collections$UnmodifiableSet)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x000000070794e158> (a sun.nio.ch.EPollSelectorImpl)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.transport.socket.nio.NioProcessor.select(NioProcessor.java:72)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:1077)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "NioProcessor-4" prio=10 tid=0x00002aaab404d800 nid=0x586f runnable [0x0000000042f88000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: RUNNABLE
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:210)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:65)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x00000007079664b0> (a sun.nio.ch.Util$2)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x00000007079664a0> (a java.util.Collections$UnmodifiableSet)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x0000000707966298> (a sun.nio.ch.EPollSelectorImpl)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.transport.socket.nio.NioProcessor.select(NioProcessor.java:72)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:1077)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "NioSocketAcceptor-1" prio=10 tid=0x00002aaab460d800 nid=0x561c runnable [0x0000000042b84000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: RUNNABLE
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:210)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:65)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x0000000707918b00> (a sun.nio.ch.Util$2)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x0000000707918af0> (a java.util.Collections$UnmodifiableSet)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x0000000707918638> (a sun.nio.ch.EPollSelectorImpl)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:84)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.transport.socket.nio.NioSocketAcceptor.select(NioSocketAcceptor.java:234)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.polling.AbstractPollingIoAcceptor$Acceptor.run(AbstractPollingIoAcceptor.java:417)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "NioSocketAcceptor-2" prio=10 tid=0x00002aaab4632800 nid=0x561b runnable [0x0000000042a83000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: RUNNABLE
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:210)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:65)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x000000070793e560> (a sun.nio.ch.Util$2)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x000000070793e550> (a java.util.Collections$UnmodifiableSet)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x000000070793e088> (a sun.nio.ch.EPollSelectorImpl)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:84)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.transport.socket.nio.NioSocketAcceptor.select(NioSocketAcceptor.java:234)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.core.polling.AbstractPollingIoAcceptor$Acceptor.run(AbstractPollingIoAcceptor.java:417)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "Thread-2" daemon prio=10 tid=0x00002aaab450e000 nid=0x561a waiting on condition [0x0000000042982000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: TIMED_WAITING (sleeping)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.sleep(Native Method)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.replication.provider.SyncReplRequestHandler$1.run(SyncReplRequestHandler.java:1098)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "Thread-1" daemon prio=10 tid=0x00002aaab45a3000 nid=0x5619 in Object.wait() [0x0000000042881000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: TIMED_WAITING (on object monitor)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Object.wait(Native Method)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- waiting on <0x000000070796ede0> (a java.lang.Object)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.apache.directory.server.ldap.replication.provider.ReplicaEventLogJanitor.run(ReplicaEventLogJanitor.java:172)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x000000070796ede0> (a java.lang.Object)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "kdcReplayCache.data" daemon prio=10 tid=0x00002aaab2d09000 nid=0x5617 waiting on condition [0x0000000042780000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: TIMED_WAITING (parking)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.misc.Unsafe.park(Native Method)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- parking to wait for  <0x000000070704fbe8> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.DelayQueue.take(DelayQueue.java:164)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:609)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:602)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:957)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:917)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "groupCache.data" daemon prio=10 tid=0x00002aaab2f2a800 nid=0x5616 waiting on condition [0x000000004267f000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: TIMED_WAITING (parking)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.misc.Unsafe.park(Native Method)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- parking to wait for  <0x0000000707069150> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.DelayQueue.take(DelayQueue.java:164)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:609)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:602)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:957)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:917)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "ou=system.data" daemon prio=10 tid=0x00002aaab2c5f000 nid=0x5615 waiting on condition [0x000000004257e000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: TIMED_WAITING (parking)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.misc.Unsafe.park(Native Method)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- parking to wait for  <0x0000000707046cb0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.DelayQueue.take(DelayQueue.java:164)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:609)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:602)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:957)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:917)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "changePwdReplayCache.data" daemon prio=10 tid=0x00000000410a4800 nid=0x5614 waiting on condition [0x00000000414c2000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: TIMED_WAITING (parking)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at sun.misc.Unsafe.park(Native Method)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- parking to wait for  <0x00000007070607a8> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.DelayQueue.take(DelayQueue.java:164)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:609)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:602)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:957)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:917)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "apacheds" daemon prio=10 tid=0x00002aaab2cde800 nid=0x5613 in Object.wait() [0x00000000413c1000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: WAITING (on object monitor)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Object.wait(Native Method)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- waiting on <0x0000000707131ad8> (a java.util.TaskQueue)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Object.wait(Object.java:485)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.TimerThread.mainLoop(Timer.java:483)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x0000000707131ad8> (a java.util.TaskQueue)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.util.TimerThread.run(Timer.java:462)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "DestroyJavaVM" prio=10 tid=0x00002aaab4091800 nid=0x55ff waiting on condition [0x0000000000000000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: RUNNABLE
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "Wrapper-Connection" daemon prio=10 tid=0x00002aaab404f000 nid=0x560f runnable [0x000000004237c000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: RUNNABLE
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.net.SocketInputStream.socketRead0(Native Method)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.net.SocketInputStream.read(SocketInputStream.java:129)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.net.SocketInputStream.read(SocketInputStream.java:182)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.io.DataInputStream.readByte(DataInputStream.java:248)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.tanukisoftware.wrapper.WrapperManager.handleSocket(WrapperManager.java:3737)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.tanukisoftware.wrapper.WrapperManager.run(WrapperManager.java:4084)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.run(Thread.java:662)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "Wrapper-Control-Event-Monitor" daemon prio=10 tid=0x00002aaab4004800 nid=0x560d sleeping[0x000000004217a000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: TIMED_WAITING (sleeping)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Thread.sleep(Native Method)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at org.tanukisoftware.wrapper.WrapperManager$3.run(WrapperManager.java:731)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "Low Memory Detector" daemon prio=10 tid=0x0000000040fac000 nid=0x560a runnable [0x0000000000000000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: RUNNABLE
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "C2 CompilerThread1" daemon prio=10 tid=0x0000000040faa000 nid=0x5609 waiting on condition [0x0000000000000000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: RUNNABLE
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "C2 CompilerThread0" daemon prio=10 tid=0x0000000040fa4800 nid=0x5608 waiting on condition [0x0000000000000000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: RUNNABLE
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "Signal Dispatcher" daemon prio=10 tid=0x0000000040fa2800 nid=0x5607 waiting on condition [0x0000000000000000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: RUNNABLE
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "Finalizer" daemon prio=10 tid=0x0000000040f80800 nid=0x5606 in Object.wait() [0x0000000041deb000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: WAITING (on object monitor)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Object.wait(Native Method)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- waiting on <0x0000000707173c18> (a java.lang.ref.ReferenceQueue$Lock)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:118)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x0000000707173c18> (a java.lang.ref.ReferenceQueue$Lock)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:134)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:171)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "Reference Handler" daemon prio=10 tid=0x0000000040f7e800 nid=0x5605 in Object.wait() [0x0000000041cea000]
INFO   | jvm 1    | 2013/07/09 11:38:16 |    java.lang.Thread.State: WAITING (on object monitor)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Object.wait(Native Method)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- waiting on <0x00000007071cd088> (a java.lang.ref.Reference$Lock)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.Object.wait(Object.java:485)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:116)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 	- locked <0x00000007071cd088> (a java.lang.ref.Reference$Lock)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "VM Thread" prio=10 tid=0x0000000040f77800 nid=0x5604 runnable 
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "GC task thread#0 (ParallelGC)" prio=10 tid=0x0000000040f2d000 nid=0x5600 runnable 
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "GC task thread#1 (ParallelGC)" prio=10 tid=0x0000000040f2e800 nid=0x5601 runnable 
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "GC task thread#2 (ParallelGC)" prio=10 tid=0x0000000040f30800 nid=0x5602 runnable 
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "GC task thread#3 (ParallelGC)" prio=10 tid=0x0000000040f32800 nid=0x5603 runnable 
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | "VM Periodic Task Thread" prio=10 tid=0x0000000040fb7000 nid=0x560b waiting on condition 
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | JNI global references: 1725
INFO   | jvm 1    | 2013/07/09 11:38:16 | 
INFO   | jvm 1    | 2013/07/09 11:38:16 | Heap
INFO   | jvm 1    | 2013/07/09 11:38:16 |  PSYoungGen     
INFO   | jvm 1    | 2013/07/09 11:38:16 |  total 1352704K, used 292143K [0x00000007ac600000, 0x00000007fff70000, 0x0000000800000000)
INFO   | jvm 1    | 2013/07/09 11:38:16 |   eden space 1336064K, 21% used [0x00000007ac600000,0x00000007be0e38c0,0x00000007fdec0000)
INFO   | jvm 1    | 2013/07/09 11:38:16 |   from space 16640K, 70% used [0x00000007fdec0000,0x00000007fea28200,0x00000007fef00000)
INFO   | jvm 1    | 2013/07/09 11:38:16 |   to   space 16192K, 0% used [0x00000007fefa0000,0x00000007fefa0000,0x00000007fff70000)
INFO   | jvm 1    | 2013/07/09 11:38:16 |  PSOldGen        total 325568K, used 322302K [0x0000000705200000, 0x0000000718ff0000, 0x00000007ac600000)
INFO   | jvm 1    | 2013/07/09 11:38:16 |   object space 325568K, 98% used [0x0000000705200000,0x0000000718cbfb08,0x0000000718ff0000)
INFO   | jvm 1    | 2013/07/09 11:38:16 |  PSPermGen       total 23424K, used 23078K [0x0000000700000000, 0x00000007016e0000, 0x0000000705200000)
INFO   | jvm 1    | 2013/07/09 11:38:16 |   object space 23424K, 98% used [0x0000000700000000,0x00000007016898f8,0x00000007016e0000)
INFO   | jvm 1    | 2013/07/09 11:38:16 | 


Regards,

Slavomir Kocka

On Jul 9, 2013, at 11:26 , Emmanuel Lécharny <elecharny@gmail.com> wrote:

> Le 7/9/13 10:27 AM, Slavomir Kocka a écrit :
>> Hi,
>> 
>> We use apacheds as authentication storage for websphere application server cluster.
>> Versions are 2.0.0.M14 and Websphere is 8.0.0.5
>> 
>> We run it on redhat 5.8 (x64), and apacheds is allways running on 100%... :
>> 
>> 22014 apacheds  22   0 4391m 1.7g  10m S 109.8 10.6 200:34.23 java 
>> 
>> Is this normal, or is it some configuration issue? When used (for writing, reading) we go up to 200% of CPU...
>> Any idea, what is going on, or how to analyze issue?
> 
> Can you produce a thread dump ?
> 
> 
> -- 
> Regards,
> Cordialement,
> Emmanuel Lécharny
> www.iktek.com 
> 


Mime
View raw message