You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@directory.apache.org by "Tyler Neemann (JIRA)" <ji...@apache.org> on 2015/11/15 01:06:11 UTC
[jira] [Commented] (DIRSERVER-2107) ApacheDS 2.0.0M20 in
production, getting ERR_554 double get for block 27
[ https://issues.apache.org/jira/browse/DIRSERVER-2107?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15005675#comment-15005675 ]
Tyler Neemann commented on DIRSERVER-2107:
------------------------------------------
Apparently I cannot add a file. Sorry for the length of this comment.
INFO | jvm 1 | 2015/11/14 15:07:00 | java.lang.ArrayIndexOutOfBoundsException: -23422
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.BlockIo.readInt(BlockIo.java:282)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.RecordHeader.getAvailableSize(RecordHeader.java:105)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.PhysicalRowIdManager.allocNew(PhysicalRowIdManager.java:216)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.PhysicalRowIdManager.alloc(PhysicalRowIdManager.java:177)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.PhysicalRowIdManager.update(PhysicalRowIdManager.java:101)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.BaseRecordManager.update(BaseRecordManager.java:281)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.CacheRecordManager.updateCacheEntries(CacheRecordManager.java:417)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.CacheRecordManager.commit(CacheRecordManager.java:349)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable.sync(JdbmTable.java:977)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmPartition.sync(JdbmPartition.java:332)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.add(AbstractBTreePartition.java:861)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.shared.partition.DefaultPartitionNexus.add(DefaultPartitionNexus.java:352)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor$1.add(BaseInterceptor.java:165)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:422)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.journal.JournalInterceptor.add(JournalInterceptor.java:139)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:422)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.trigger.TriggerInterceptor.add(TriggerInterceptor.java:300)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:422)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.event.EventInterceptor.add(EventInterceptor.java:226)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:422)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.subtree.SubentryInterceptor.add(SubentryInterceptor.java:1014)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:422)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.collective.CollectiveAttributeInterceptor.add(CollectiveAttributeInterceptor.java:134)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:422)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.operational.OperationalAttributeInterceptor.add(OperationalAttributeInterceptor.java:232)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:422)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.schema.SchemaInterceptor.add(SchemaInterceptor.java:1101)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:422)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.hash.PasswordHashingInterceptor.add(PasswordHashingInterceptor.java:93)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:422)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.exception.ExceptionInterceptor.add(ExceptionInterceptor.java:189)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:422)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.admin.AdministrativePointInterceptor.add(AdministrativePointInterceptor.java:1201)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:422)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.authz.AciAuthorizationInterceptor.add(AciAuthorizationInterceptor.java:515)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:422)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.referral.ReferralInterceptor.add(ReferralInterceptor.java:249)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:422)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.authn.AuthenticationInterceptor.add(AuthenticationInterceptor.java:410)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:422)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.normalization.NormalizationInterceptor.add(NormalizationInterceptor.java:131)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.DefaultOperationManager.add(DefaultOperationManager.java:394)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.shared.DefaultCoreSession.add(DefaultCoreSession.java:249)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.shared.DefaultCoreSession.add(DefaultCoreSession.java:233)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.request.AddRequestHandler.handle(AddRequestHandler.java:57)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.request.AddRequestHandler.handle(AddRequestHandler.java:39)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:207)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:56)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:221)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:217)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:854)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:542)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1300(DefaultIoFilterChain.java:48)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:943)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:475)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:429)
INFO | jvm 1 | 2015/11/14 15:07:00 | at java.lang.Thread.run(Unknown Source)
INFO | jvm 1 | 2015/11/14 15:07:00 | [15:07:00] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> SearchOperation : SearchContext for Dn 'ou=xxxxxxxxxxx', filter :'(objectClass=*)'
INFO | jvm 1 | 2015/11/14 15:07:00 | [15:07:00] DEBUG [org.apache.directory.server.OPERATION_LOG] - << SearchOperation successful
INFO | jvm 1 | 2015/11/14 15:07:00 | [15:07:00] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> SearchOperation : SearchContext for Dn 'xxxxxxxxxxx', filter :'(objectClass=*)'
INFO | jvm 1 | 2015/11/14 15:07:00 | [15:07:00] DEBUG [org.apache.directory.server.OPERATION_LOG] - << SearchOperation successful
INFO | jvm 1 | 2015/11/14 15:07:00 | [15:07:00] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> SearchOperation : SearchContext for Dn 'xxxxxxxxxxx', filter :'(objectClass=*)'
INFO | jvm 1 | 2015/11/14 15:07:00 | [15:07:00] DEBUG [org.apache.directory.server.OPERATION_LOG] - << SearchOperation successful
INFO | jvm 1 | 2015/11/14 15:07:00 | [15:07:00] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> SearchOperation : SearchContext for Dn 'xxxxxxxxxxx', filter :'(objectClass=*)'
INFO | jvm 1 | 2015/11/14 15:07:00 | [15:07:00] DEBUG [org.apache.directory.server.OPERATION_LOG] - << SearchOperation successful
INFO | jvm 1 | 2015/11/14 15:07:00 | Exception in thread "pool-4-thread-1" java.lang.Error: ERR_554 double get for block 27,851
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.RecordFile.get(RecordFile.java:185)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.PhysicalRowIdManager.allocNew(PhysicalRowIdManager.java:202)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.PhysicalRowIdManager.alloc(PhysicalRowIdManager.java:177)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.PhysicalRowIdManager.update(PhysicalRowIdManager.java:101)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.BaseRecordManager.update(BaseRecordManager.java:281)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.CacheRecordManager$CacheListener.cacheObjectEvicted(CacheRecordManager.java:459)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.CacheRecordManager$CacheListener.cacheObjectEvicted(CacheRecordManager.java:444)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.helper.MRU.purgeEntry(MRU.java:310)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.helper.MRU.put(MRU.java:128)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.CacheRecordManager.fetch(CacheRecordManager.java:268)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.btree.BPage.loadBPage(BPage.java:949)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.btree.BPage.find(BPage.java:280)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.btree.BTree.find(BTree.java:413)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable.get(JdbmTable.java:344)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1264)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1213)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.xdbm.search.evaluator.OneLevelScopeEvaluator.evaluate(OneLevelScopeEvaluator.java:109)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:167)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:43)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.api.filtering.EntryFilteringCursorImpl.next(EntryFilteringCursorImpl.java:377)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.writeResults(SearchRequestHandler.java:397)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.doSimpleSearch(SearchRequestHandler.java:857)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleIgnoringReferrals(SearchRequestHandler.java:1179)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleWithReferrals(SearchRequestHandler.java:1272)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:223)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:93)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:207)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:56)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:221)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:217)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:854)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:542)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1300(DefaultIoFilterChain.java:48)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:943)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:475)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:429)
INFO | jvm 1 | 2015/11/14 15:07:00 | at java.lang.Thread.run(Unknown Source)
INFO | jvm 1 | 2015/11/14 15:07:00 | Exception in thread "pool-4-thread-7" java.lang.Error: ERR_554 double get for block 27,851
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.RecordFile.get(RecordFile.java:185)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.PhysicalRowIdManager.allocNew(PhysicalRowIdManager.java:202)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.PhysicalRowIdManager.alloc(PhysicalRowIdManager.java:177)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.PhysicalRowIdManager.update(PhysicalRowIdManager.java:101)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.BaseRecordManager.update(BaseRecordManager.java:281)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.CacheRecordManager$CacheListener.cacheObjectEvicted(CacheRecordManager.java:459)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.CacheRecordManager$CacheListener.cacheObjectEvicted(CacheRecordManager.java:444)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.helper.MRU.purgeEntry(MRU.java:310)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.helper.MRU.put(MRU.java:128)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.CacheRecordManager.fetch(CacheRecordManager.java:268)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.btree.BPage.loadBPage(BPage.java:949)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.btree.BPage.find(BPage.java:280)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.btree.BTree.find(BTree.java:413)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable.get(JdbmTable.java:344)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1264)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1213)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.xdbm.search.evaluator.OneLevelScopeEvaluator.evaluate(OneLevelScopeEvaluator.java:109)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:167)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:43)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.api.filtering.EntryFilteringCursorImpl.next(EntryFilteringCursorImpl.java:377)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.writeResults(SearchRequestHandler.java:397)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.doSimpleSearch(SearchRequestHandler.java:857)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleIgnoringReferrals(SearchRequestHandler.java:1179)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleWithReferrals(SearchRequestHandler.java:1272)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:223)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:93)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:207)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:56)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:221)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:217)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:854)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:542)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1300(DefaultIoFilterChain.java:48)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:943)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:475)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:429)
INFO | jvm 1 | 2015/11/14 15:07:00 | at java.lang.Thread.run(Unknown Source)
INFO | jvm 1 | 2015/11/14 15:07:00 | [15:07:00] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> LookupOperation : FilteringOperationContext for Dn 'xxxxxxxxxxx', *
INFO | jvm 1 | 2015/11/14 15:07:00 | [15:07:00] DEBUG [org.apache.directory.server.OPERATION_LOG] - << LookupOperation successful
INFO | jvm 1 | 2015/11/14 15:07:00 | [15:07:00] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> BindOperation : BindContext for Dn 'xxxxxxxxxxx', credentials <xxxxxxxxxxx >
INFO | jvm 1 | 2015/11/14 15:07:00 | [15:07:00] DEBUG [org.apache.directory.server.OPERATION_LOG] - << BindOperation successful
INFO | jvm 1 | 2015/11/14 15:07:00 | [15:07:00] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> SearchOperation : SearchContext for Dn 'xxxxxxxxxxx', filter :'(objectClass=*)'
INFO | jvm 1 | 2015/11/14 15:07:00 | [15:07:00] DEBUG [org.apache.directory.server.OPERATION_LOG] - << SearchOperation successful
INFO | jvm 1 | 2015/11/14 15:07:00 | Exception in thread "pool-4-thread-3" java.lang.Error: ERR_554 double get for block 27,851
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.RecordFile.get(RecordFile.java:185)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.PhysicalRowIdManager.allocNew(PhysicalRowIdManager.java:202)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.PhysicalRowIdManager.alloc(PhysicalRowIdManager.java:177)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.PhysicalRowIdManager.update(PhysicalRowIdManager.java:101)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.BaseRecordManager.update(BaseRecordManager.java:281)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.CacheRecordManager$CacheListener.cacheObjectEvicted(CacheRecordManager.java:459)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.CacheRecordManager$CacheListener.cacheObjectEvicted(CacheRecordManager.java:444)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.helper.MRU.purgeEntry(MRU.java:310)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.helper.MRU.put(MRU.java:128)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.recman.CacheRecordManager.fetch(CacheRecordManager.java:268)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.btree.BPage.loadBPage(BPage.java:949)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.btree.BPage.find(BPage.java:280)
INFO | jvm 1 | 2015/11/14 15:07:00 | at jdbm.btree.BTree.find(BTree.java:413)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable.get(JdbmTable.java:344)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1264)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1213)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.xdbm.search.evaluator.OneLevelScopeEvaluator.evaluate(OneLevelScopeEvaluator.java:109)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:167)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:43)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.core.api.filtering.EntryFilteringCursorImpl.next(EntryFilteringCursorImpl.java:377)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.writeResults(SearchRequestHandler.java:397)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.doSimpleSearch(SearchRequestHandler.java:857)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleIgnoringReferrals(SearchRequestHandler.java:1179)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleWithReferrals(SearchRequestHandler.java:1272)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:223)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:93)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:207)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:56)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:221)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:217)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:854)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:542)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1300(DefaultIoFilterChain.java:48)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:943)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:475)
INFO | jvm 1 | 2015/11/14 15:07:00 | at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:429)
INFO | jvm 1 | 2015/11/14 15:07:00 | at java.lang.Thread.run(Unknown Source)
INFO | jvm 1 | 2015/11/14 15:07:02 | [15:07:02] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> LookupOperation : FilteringOperationContext for Dn 'xxxxxxxxxxx', *
INFO | jvm 1 | 2015/11/14 15:07:02 | [15:07:02] DEBUG [org.apache.directory.server.OPERATION_LOG] - << LookupOperation successful
INFO | jvm 1 | 2015/11/14 15:07:02 | [15:07:02] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> BindOperation : BindContext for Dn 'xxxxxxxxxxx', credentials <xxxxxxxxxxx >
INFO | jvm 1 | 2015/11/14 15:07:02 | [15:07:02] DEBUG [org.apache.directory.server.OPERATION_LOG] - << BindOperation successful
INFO | jvm 1 | 2015/11/14 15:07:02 | [15:07:02] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> SearchOperation : SearchContext for Dn 'xxxxxxxxxxx', filter :'(&(objectClass=inetOrgPerson)(xxxxxxxxxxx))'
INFO | jvm 1 | 2015/11/14 15:07:02 | [15:07:02] DEBUG [org.apache.directory.server.OPERATION_LOG] - << SearchOperation successful
INFO | jvm 1 | 2015/11/14 15:07:02 | Exception in thread "pool-4-thread-2" java.lang.Error: ERR_554 double get for block 27,851
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.recman.RecordFile.get(RecordFile.java:185)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.recman.PhysicalRowIdManager.allocNew(PhysicalRowIdManager.java:202)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.recman.PhysicalRowIdManager.alloc(PhysicalRowIdManager.java:177)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.recman.PhysicalRowIdManager.update(PhysicalRowIdManager.java:101)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.recman.BaseRecordManager.update(BaseRecordManager.java:281)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.recman.CacheRecordManager$CacheListener.cacheObjectEvicted(CacheRecordManager.java:459)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.recman.CacheRecordManager$CacheListener.cacheObjectEvicted(CacheRecordManager.java:444)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.helper.MRU.purgeEntry(MRU.java:310)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.helper.MRU.put(MRU.java:128)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.recman.CacheRecordManager.fetch(CacheRecordManager.java:268)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.btree.BPage.loadBPage(BPage.java:949)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.btree.BPage.find(BPage.java:280)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.btree.BTree.find(BTree.java:413)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable.get(JdbmTable.java:344)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1264)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1213)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.xdbm.search.evaluator.EqualityEvaluator.evaluate(EqualityEvaluator.java:97)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.xdbm.search.evaluator.AndEvaluator.evaluate(AndEvaluator.java:110)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.xdbm.search.evaluator.AndEvaluator.evaluate(AndEvaluator.java:110)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:167)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:43)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.core.api.filtering.EntryFilteringCursorImpl.next(EntryFilteringCursorImpl.java:377)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.writeResults(SearchRequestHandler.java:397)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.doSimpleSearch(SearchRequestHandler.java:857)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleIgnoringReferrals(SearchRequestHandler.java:1179)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleWithReferrals(SearchRequestHandler.java:1272)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:223)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:93)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:207)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:56)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:221)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:217)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:854)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:542)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1300(DefaultIoFilterChain.java:48)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:943)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:475)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:429)
INFO | jvm 1 | 2015/11/14 15:07:02 | at java.lang.Thread.run(Unknown Source)
INFO | jvm 1 | 2015/11/14 15:07:02 | [15:07:02] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> LookupOperation : FilteringOperationContext for Dn 'xxxxxxxxxxx', *
INFO | jvm 1 | 2015/11/14 15:07:02 | [15:07:02] DEBUG [org.apache.directory.server.OPERATION_LOG] - << LookupOperation successful
INFO | jvm 1 | 2015/11/14 15:07:02 | [15:07:02] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> BindOperation : BindContext for Dn 'xxxxxxxxxxx', credentials <xxxxxxxxxxx>
INFO | jvm 1 | 2015/11/14 15:07:02 | [15:07:02] DEBUG [org.apache.directory.server.OPERATION_LOG] - << BindOperation successful
INFO | jvm 1 | 2015/11/14 15:07:02 | [15:07:02] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> SearchOperation : SearchContext for Dn 'xxxxxxxxxxx', filter :'(&(objectClass=inetOrgPerson)(xxxxxxxxxxx))'
INFO | jvm 1 | 2015/11/14 15:07:02 | [15:07:02] DEBUG [org.apache.directory.server.OPERATION_LOG] - << SearchOperation successful
INFO | jvm 1 | 2015/11/14 15:07:02 | Exception in thread "pool-4-thread-6" java.lang.Error: ERR_554 double get for block 27,851
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.recman.RecordFile.get(RecordFile.java:185)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.recman.PhysicalRowIdManager.allocNew(PhysicalRowIdManager.java:202)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.recman.PhysicalRowIdManager.alloc(PhysicalRowIdManager.java:177)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.recman.PhysicalRowIdManager.update(PhysicalRowIdManager.java:101)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.recman.BaseRecordManager.update(BaseRecordManager.java:281)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.recman.CacheRecordManager$CacheListener.cacheObjectEvicted(CacheRecordManager.java:459)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.recman.CacheRecordManager$CacheListener.cacheObjectEvicted(CacheRecordManager.java:444)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.helper.MRU.purgeEntry(MRU.java:310)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.helper.MRU.put(MRU.java:128)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.recman.CacheRecordManager.fetch(CacheRecordManager.java:268)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.btree.BPage.loadBPage(BPage.java:949)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.btree.BPage.find(BPage.java:280)
INFO | jvm 1 | 2015/11/14 15:07:02 | at jdbm.btree.BTree.find(BTree.java:413)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable.get(JdbmTable.java:344)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1264)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1213)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.xdbm.search.evaluator.EqualityEvaluator.evaluate(EqualityEvaluator.java:97)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.xdbm.search.evaluator.AndEvaluator.evaluate(AndEvaluator.java:110)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.xdbm.search.evaluator.AndEvaluator.evaluate(AndEvaluator.java:110)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:167)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:43)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.core.api.filtering.EntryFilteringCursorImpl.next(EntryFilteringCursorImpl.java:377)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.writeResults(SearchRequestHandler.java:397)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.doSimpleSearch(SearchRequestHandler.java:857)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleIgnoringReferrals(SearchRequestHandler.java:1179)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleWithReferrals(SearchRequestHandler.java:1272)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:223)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:93)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:207)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:56)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:221)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:217)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:854)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:542)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1300(DefaultIoFilterChain.java:48)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:943)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:475)
INFO | jvm 1 | 2015/11/14 15:07:02 | at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:429)
INFO | jvm 1 | 2015/11/14 15:07:02 | at java.lang.Thread.run(Unknown Source)
INFO | jvm 1 | 2015/11/14 15:07:09 | [15:07:09] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> LookupOperation : FilteringOperationContext for Dn 'xxxxxxxxxxx', *
INFO | jvm 1 | 2015/11/14 15:07:09 | [15:07:09] DEBUG [org.apache.directory.server.OPERATION_LOG] - << LookupOperation successful
INFO | jvm 1 | 2015/11/14 15:07:09 | [15:07:09] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> BindOperation : BindContext for Dn 'xxxxxxxxxxx', credentials <xxxxxxxxxxx >
INFO | jvm 1 | 2015/11/14 15:07:09 | [15:07:09] DEBUG [org.apache.directory.server.OPERATION_LOG] - << BindOperation successful
INFO | jvm 1 | 2015/11/14 15:07:09 | [15:07:09] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> SearchOperation : SearchContext for Dn 'xxxxxxxxxxx', filter :'(&(objectClass=inetOrgPerson)(xxxxxxxxxxx))'
INFO | jvm 1 | 2015/11/14 15:07:09 | [15:07:09] DEBUG [org.apache.directory.server.OPERATION_LOG] - << SearchOperation successful
INFO | jvm 1 | 2015/11/14 15:07:09 | Exception in thread "pool-4-thread-4" java.lang.Error: ERR_554 double get for block 27,851
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.recman.RecordFile.get(RecordFile.java:185)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.recman.PhysicalRowIdManager.allocNew(PhysicalRowIdManager.java:202)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.recman.PhysicalRowIdManager.alloc(PhysicalRowIdManager.java:177)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.recman.PhysicalRowIdManager.update(PhysicalRowIdManager.java:101)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.recman.BaseRecordManager.update(BaseRecordManager.java:281)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.recman.CacheRecordManager$CacheListener.cacheObjectEvicted(CacheRecordManager.java:459)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.recman.CacheRecordManager$CacheListener.cacheObjectEvicted(CacheRecordManager.java:444)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.helper.MRU.purgeEntry(MRU.java:310)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.helper.MRU.put(MRU.java:128)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.recman.CacheRecordManager.fetch(CacheRecordManager.java:268)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.btree.BPage.loadBPage(BPage.java:949)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.btree.BPage.find(BPage.java:280)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.btree.BTree.find(BTree.java:413)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable.get(JdbmTable.java:344)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1264)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1213)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.xdbm.search.evaluator.EqualityEvaluator.evaluate(EqualityEvaluator.java:97)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.xdbm.search.evaluator.AndEvaluator.evaluate(AndEvaluator.java:110)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.xdbm.search.evaluator.AndEvaluator.evaluate(AndEvaluator.java:110)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:167)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:43)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.core.api.filtering.EntryFilteringCursorImpl.next(EntryFilteringCursorImpl.java:377)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.writeResults(SearchRequestHandler.java:397)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.doSimpleSearch(SearchRequestHandler.java:857)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleIgnoringReferrals(SearchRequestHandler.java:1179)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleWithReferrals(SearchRequestHandler.java:1272)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:223)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:93)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:207)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:56)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:221)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:217)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:854)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:542)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1300(DefaultIoFilterChain.java:48)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:943)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:475)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:429)
INFO | jvm 1 | 2015/11/14 15:07:09 | at java.lang.Thread.run(Unknown Source)
INFO | jvm 1 | 2015/11/14 15:07:09 | [15:07:09] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> LookupOperation : FilteringOperationContext for Dn 'xxxxxxxxxxx', *
INFO | jvm 1 | 2015/11/14 15:07:09 | [15:07:09] DEBUG [org.apache.directory.server.OPERATION_LOG] - << LookupOperation successful
INFO | jvm 1 | 2015/11/14 15:07:09 | [15:07:09] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> BindOperation : BindContext for Dn 'xxxxxxxxxxx', credentials <xxxxxxxxxxx >
INFO | jvm 1 | 2015/11/14 15:07:09 | [15:07:09] DEBUG [org.apache.directory.server.OPERATION_LOG] - << BindOperation successful
INFO | jvm 1 | 2015/11/14 15:07:09 | [15:07:09] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> SearchOperation : SearchContext for Dn 'xxxxxxxxxxx', filter :'(&(objectClass=inetOrgPerson)(xxxxxxxxxxx))'
INFO | jvm 1 | 2015/11/14 15:07:09 | [15:07:09] DEBUG [org.apache.directory.server.OPERATION_LOG] - << SearchOperation successful
INFO | jvm 1 | 2015/11/14 15:07:09 | Exception in thread "pool-4-thread-5" java.lang.Error: ERR_554 double get for block 27,851
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.recman.RecordFile.get(RecordFile.java:185)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.recman.PhysicalRowIdManager.allocNew(PhysicalRowIdManager.java:202)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.recman.PhysicalRowIdManager.alloc(PhysicalRowIdManager.java:177)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.recman.PhysicalRowIdManager.update(PhysicalRowIdManager.java:101)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.recman.BaseRecordManager.update(BaseRecordManager.java:281)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.recman.CacheRecordManager$CacheListener.cacheObjectEvicted(CacheRecordManager.java:459)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.recman.CacheRecordManager$CacheListener.cacheObjectEvicted(CacheRecordManager.java:444)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.helper.MRU.purgeEntry(MRU.java:310)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.helper.MRU.put(MRU.java:128)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.recman.CacheRecordManager.fetch(CacheRecordManager.java:268)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.btree.BPage.loadBPage(BPage.java:949)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.btree.BPage.find(BPage.java:280)
INFO | jvm 1 | 2015/11/14 15:07:09 | at jdbm.btree.BTree.find(BTree.java:413)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable.get(JdbmTable.java:344)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1264)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1213)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.xdbm.search.evaluator.EqualityEvaluator.evaluate(EqualityEvaluator.java:97)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.xdbm.search.evaluator.AndEvaluator.evaluate(AndEvaluator.java:110)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.xdbm.search.evaluator.AndEvaluator.evaluate(AndEvaluator.java:110)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:167)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.core.partition.impl.btree.EntryCursorAdaptor.get(EntryCursorAdaptor.java:43)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.core.api.filtering.EntryFilteringCursorImpl.next(EntryFilteringCursorImpl.java:377)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.writeResults(SearchRequestHandler.java:397)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.doSimpleSearch(SearchRequestHandler.java:857)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleIgnoringReferrals(SearchRequestHandler.java:1179)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleWithReferrals(SearchRequestHandler.java:1272)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:223)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:93)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:207)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:56)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:221)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:217)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:854)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:542)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1300(DefaultIoFilterChain.java:48)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:943)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:475)
INFO | jvm 1 | 2015/11/14 15:07:09 | at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:429)
INFO | jvm 1 | 2015/11/14 15:07:09 | at java.lang.Thread.run(Unknown Source)
INFO | jvm 1 | 2015/11/14 15:07:09 | [15:07:09] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> ModifyOperation : ModifyContext for Dn 'ads-dsReplicaId=1,ou=consumers,ou=system', modifications :
INFO | jvm 1 | 2015/11/14 15:07:09 | Modification: replace
INFO | jvm 1 | 2015/11/14 15:07:09 | , attribute : ads-replLastSentCsn: 20151114200700.162000Z#000000#001#000000
INFO | jvm 1 | 2015/11/14 15:07:09 |
INFO | jvm 1 | 2015/11/14 15:07:10 | [15:07:09] DEBUG [org.apache.directory.server.OPERATION_LOG] - << ModifyOperation successful
INFO | jvm 1 | 2015/11/14 15:07:12 | [15:07:12] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> LookupOperation : FilteringOperationContext for Dn xxxxxxxxxxx, *
INFO | jvm 1 | 2015/11/14 15:07:12 | [15:07:12] DEBUG [org.apache.directory.server.OPERATION_LOG] - << LookupOperation successful
INFO | jvm 1 | 2015/11/14 15:07:12 | [15:07:12] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> BindOperation : BindContext for Dn xxxxxxxxxxx, credentials xxxxxxxxxxx
INFO | jvm 1 | 2015/11/14 15:07:12 | [15:07:12] DEBUG [org.apache.directory.server.OPERATION_LOG] - << BindOperation successful
INFO | jvm 1 | 2015/11/14 15:07:12 | [15:07:12] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> SearchOperation : SearchContext for Dn xxxxxxxxxxx, filter :'(&(objectClass=inetOrgPerson)(xxxxxxxxxxx))'
INFO | jvm 1 | 2015/11/14 15:07:12 | [15:07:12] DEBUG [org.apache.directory.server.OPERATION_LOG] - << SearchOperation successful
INFO | jvm 1 | 2015/11/14 15:07:12 | [15:07:12] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> SearchOperation : SearchContext for Dn xxxxxxxxxxx, filter :'(ou=MAU)'
INFO | jvm 1 | 2015/11/14 15:07:12 | [15:07:12] DEBUG [org.apache.directory.server.OPERATION_LOG] - << SearchOperation successful
INFO | jvm 1 | 2015/11/14 15:07:12 | [15:07:12] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> SearchOperation : SearchContext for Dn xxxxxxxxxxx, filter :'(objectClass=*)'
INFO | jvm 1 | 2015/11/14 15:07:12 | [15:07:12] DEBUG [org.apache.directory.server.OPERATION_LOG] - << SearchOperation successful
INFO | jvm 1 | 2015/11/14 15:07:12 | [15:07:12] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> SearchOperation : SearchContext for Dn xxxxxxxxxxx, filter :'(objectClass=*)'
INFO | jvm 1 | 2015/11/14 15:07:12 | Exception in thread "pool-4-thread-8" java.lang.Error: ERR_554 double get for block 27,851
INFO | jvm 1 | 2015/11/14 15:07:12 | at jdbm.recman.RecordFile.get(RecordFile.java:185)
INFO | jvm 1 | 2015/11/14 15:07:12 | at jdbm.recman.PhysicalRowIdManager.allocNew(PhysicalRowIdManager.java:202)
INFO | jvm 1 | 2015/11/14 15:07:12 | at jdbm.recman.PhysicalRowIdManager.alloc(PhysicalRowIdManager.java:177)
INFO | jvm 1 | 2015/11/14 15:07:12 | at jdbm.recman.PhysicalRowIdManager.update(PhysicalRowIdManager.java:101)
INFO | jvm 1 | 2015/11/14 15:07:12 | at jdbm.recman.BaseRecordManager.update(BaseRecordManager.java:281)
INFO | jvm 1 | 2015/11/14 15:07:12 | at jdbm.recman.CacheRecordManager$CacheListener.cacheObjectEvicted(CacheRecordManager.java:459)
INFO | jvm 1 | 2015/11/14 15:07:12 | at jdbm.recman.CacheRecordManager$CacheListener.cacheObjectEvicted(CacheRecordManager.java:444)
INFO | jvm 1 | 2015/11/14 15:07:12 | at jdbm.helper.MRU.purgeEntry(MRU.java:310)
INFO | jvm 1 | 2015/11/14 15:07:12 | at jdbm.helper.MRU.put(MRU.java:128)
INFO | jvm 1 | 2015/11/14 15:07:12 | at jdbm.recman.CacheRecordManager.fetch(CacheRecordManager.java:268)
INFO | jvm 1 | 2015/11/14 15:07:12 | at jdbm.btree.BPage.loadBPage(BPage.java:949)
INFO | jvm 1 | 2015/11/14 15:07:12 | at jdbm.btree.BPage.find(BPage.java:280)
INFO | jvm 1 | 2015/11/14 15:07:12 | at jdbm.btree.BTree.find(BTree.java:413)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable.get(JdbmTable.java:344)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1264)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.xdbm.search.impl.DefaultSearchEngine.computeResult(DefaultSearchEngine.java:189)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.search(AbstractBTreePartition.java:1141)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.shared.partition.DefaultPartitionNexus.search(DefaultPartitionNexus.java:624)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor$1.search(BaseInterceptor.java:264)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:677)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.subtree.SubentryInterceptor.search(SubentryInterceptor.java:1568)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:677)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.collective.CollectiveAttributeInterceptor.search(CollectiveAttributeInterceptor.java:174)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:677)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.operational.OperationalAttributeInterceptor.search(OperationalAttributeInterceptor.java:437)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:677)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.schema.SchemaInterceptor.search(SchemaInterceptor.java:1273)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:677)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.authz.DefaultAuthorizationInterceptor.search(DefaultAuthorizationInterceptor.java:337)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:677)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.authz.AciAuthorizationInterceptor.search(AciAuthorizationInterceptor.java:1208)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:677)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.authn.AuthenticationInterceptor.search(AuthenticationInterceptor.java:1243)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:677)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.normalization.NormalizationInterceptor.search(NormalizationInterceptor.java:304)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.DefaultOperationManager.search(DefaultOperationManager.java:1339)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.core.shared.DefaultCoreSession.search(DefaultCoreSession.java:1120)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.doSimpleSearch(SearchRequestHandler.java:827)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleIgnoringReferrals(SearchRequestHandler.java:1179)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleWithReferrals(SearchRequestHandler.java:1272)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:223)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:93)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:207)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:56)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:221)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:217)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:854)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:542)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1300(DefaultIoFilterChain.java:48)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:943)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:475)
INFO | jvm 1 | 2015/11/14 15:07:12 | at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:429)
INFO | jvm 1 | 2015/11/14 15:07:12 | at java.lang.Thread.run(Unknown Source)
INFO | jvm 1 | 2015/11/14 17:47:50 | [17:47:50] INFO [org.apache.directory.server.CONSUMER_LOG] - Unbound from the server xxxxxxxxx
INFO | jvm 1 | 2015/11/14 17:47:50 | [17:47:50] INFO [org.apache.directory.server.CONSUMER_LOG] - Connection closed for the server xxxxxxxx
INFO | jvm 1 | 2015/11/14 17:47:50 | Error in WrapperListener.stop callback. java.lang.Error: ERR_554 double get for block 27,851
INFO | jvm 1 | 2015/11/14 17:47:50 | java.lang.Error: ERR_554 double get for block 27,851
INFO | jvm 1 | 2015/11/14 17:47:50 | at jdbm.recman.RecordFile.get(RecordFile.java:185)
INFO | jvm 1 | 2015/11/14 17:47:50 | at jdbm.recman.PhysicalRowIdManager.allocNew(PhysicalRowIdManager.java:202)
INFO | jvm 1 | 2015/11/14 17:47:50 | at jdbm.recman.PhysicalRowIdManager.alloc(PhysicalRowIdManager.java:177)
INFO | jvm 1 | 2015/11/14 17:47:50 | at jdbm.recman.PhysicalRowIdManager.update(PhysicalRowIdManager.java:101)
INFO | jvm 1 | 2015/11/14 17:47:50 | at jdbm.recman.BaseRecordManager.update(BaseRecordManager.java:281)
INFO | jvm 1 | 2015/11/14 17:47:50 | at jdbm.recman.CacheRecordManager$CacheListener.cacheObjectEvicted(CacheRecordManager.java:459)
INFO | jvm 1 | 2015/11/14 17:47:50 | at jdbm.recman.CacheRecordManager$CacheListener.cacheObjectEvicted(CacheRecordManager.java:444)
INFO | jvm 1 | 2015/11/14 17:47:50 | at jdbm.helper.MRU.purgeEntry(MRU.java:310)
INFO | jvm 1 | 2015/11/14 17:47:50 | at jdbm.helper.MRU.put(MRU.java:128)
INFO | jvm 1 | 2015/11/14 17:47:50 | at jdbm.recman.CacheRecordManager.fetch(CacheRecordManager.java:268)
INFO | jvm 1 | 2015/11/14 17:47:50 | at jdbm.btree.BPage.loadBPage(BPage.java:949)
INFO | jvm 1 | 2015/11/14 17:47:50 | at jdbm.btree.BPage.find(BPage.java:280)
INFO | jvm 1 | 2015/11/14 17:47:50 | at jdbm.btree.BTree.find(BTree.java:413)
INFO | jvm 1 | 2015/11/14 17:47:50 | at org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable.get(JdbmTable.java:344)
INFO | jvm 1 | 2015/11/14 17:47:50 | at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1264)
INFO | jvm 1 | 2015/11/14 17:47:50 | at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.fetch(AbstractBTreePartition.java:1213)
INFO | jvm 1 | 2015/11/14 17:47:50 | at org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.saveContextCsn(AbstractBTreePartition.java:3348)
INFO | jvm 1 | 2015/11/14 17:47:50 | at org.apache.directory.server.core.shared.partition.DefaultPartitionNexus.sync(DefaultPartitionNexus.java:319)
INFO | jvm 1 | 2015/11/14 17:47:50 | at org.apache.directory.server.core.DefaultDirectoryService.shutdown(DefaultDirectoryService.java:1283)
INFO | jvm 1 | 2015/11/14 17:47:50 | at org.apache.directory.server.ApacheDsService.stop(ApacheDsService.java:579)
INFO | jvm 1 | 2015/11/14 17:47:50 | at org.apache.directory.server.wrapper.ApacheDsTanukiWrapper.stop(ApacheDsTanukiWrapper.java:97)
INFO | jvm 1 | 2015/11/14 17:47:50 | at org.tanukisoftware.wrapper.WrapperManager$13.run(WrapperManager.java:3134)
> ApacheDS 2.0.0M20 in production, getting ERR_554 double get for block 27
> ------------------------------------------------------------------------
>
> Key: DIRSERVER-2107
> URL: https://issues.apache.org/jira/browse/DIRSERVER-2107
> Project: Directory ApacheDS
> Issue Type: Bug
> Affects Versions: 2.0.0-M20
> Environment: Server 2008 R2
> Reporter: Tyler Neemann
>
> In our production environment, ApacheDS was restarted. After about a minute after restart received error in the log. During this time, no operations could be performed. This is the third time I have seen this issue. I attached the debug output as a file. I did replace some of the confidential info with xxxxxxx in the log The errors we received were:
> java.lang.ArrayIndexOutOfBoundsException: -23422
> and
> Exception in thread "pool-4-thread-1" java.lang.Error: ERR_554 double get for block 27,851
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)