Jagannath Gowda created DIRSERVER-2187:
------------------------------------------
Summary: ApacheDs connection Closed forcefully and Not responding
Key: DIRSERVER-2187
URL: https://issues.apache.org/jira/browse/DIRSERVER-2187
Project: Directory ApacheDS
Issue Type: Bug
Components: tools
Affects Versions: 2.0.0-M23
Environment: RHEL 7
Reporter: Jagannath Gowda
Getting the following error from the ApacheDS and connection is getting closed.
Issue got resolved after restarting ApacheDS. I would like to understand the
reason to occur this issue, so that it shouldn't reoccurred in the future.
Logs:
INFO | jvm 1 | 2017/03/21 15:21:28 | [15:21:28] WARN
[org.apache.directory.server.ldap.LdapProtocolHandler] - Unexpected exception
forcing session to close: sending disconnect notice to client.
INFO | jvm 1 | 2017/03/21 15:21:28 | java.lang.Error: ERR_562 Offset too
large for record header (996:31,386)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
jdbm.recman.RecordHeader.<init>(RecordHeader.java:83)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
jdbm.recman.PhysicalRowIdManager.allocNew(PhysicalRowIdManager.java:224)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
jdbm.recman.PhysicalRowIdManager.alloc(PhysicalRowIdManager.java:177)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
jdbm.recman.PhysicalRowIdManager.update(PhysicalRowIdManager.java:101)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
jdbm.recman.BaseRecordManager.update(BaseRecordManager.java:281)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
jdbm.recman.CacheRecordManager.updateCacheEntries(CacheRecordManager.java:417)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
jdbm.recman.CacheRecordManager.commit(CacheRecordManager.java:349)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable.sync(JdbmTable.java:976)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmPartition.sync(JdbmPartition.java:577)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.add(AbstractBTreePartition.java:871)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.shared.partition.DefaultPartitionNexus.add(DefaultPartitionNexus.java:361)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor$1.add(BaseInterceptor.java:107)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.journal.JournalInterceptor.add(JournalInterceptor.java:139)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.trigger.TriggerInterceptor.add(TriggerInterceptor.java:297)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.event.EventInterceptor.add(EventInterceptor.java:229)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.subtree.SubentryInterceptor.add(SubentryInterceptor.java:1018)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.collective.CollectiveAttributeInterceptor.add(CollectiveAttributeInterceptor.java:134)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.operational.OperationalAttributeInterceptor.add(OperationalAttributeInterceptor.java:310)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.schema.SchemaInterceptor.add(SchemaInterceptor.java:1109)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.hash.ConfigurableHashingInterceptor.add(ConfigurableHashingInterceptor.java:90)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.exception.ExceptionInterceptor.add(ExceptionInterceptor.java:188)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.admin.AdministrativePointInterceptor.add(AdministrativePointInterceptor.java:1189)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.authz.AciAuthorizationInterceptor.add(AciAuthorizationInterceptor.java:518)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.referral.ReferralInterceptor.add(ReferralInterceptor.java:247)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.authn.AuthenticationInterceptor.add(AuthenticationInterceptor.java:407)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.normalization.NormalizationInterceptor.add(NormalizationInterceptor.java:131)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.DefaultOperationManager.add(DefaultOperationManager.java:397)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.shared.DefaultCoreSession.add(DefaultCoreSession.java:251)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.shared.DefaultCoreSession.add(DefaultCoreSession.java:235)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.ldap.handlers.request.AddRequestHandler.handle(AddRequestHandler.java:57)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.ldap.handlers.request.AddRequestHandler.handle(AddRequestHandler.java:39)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:207)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:56)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:243)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:216)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:854)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:542)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1300(DefaultIoFilterChain.java:48)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:943)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:476)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:430)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
java.lang.Thread.run(Thread.java:745)
INFO | jvm 1 | 2017/03/21 15:21:28 | Exception in thread
"pool-7-thread-277" java.lang.Error: ERR_562 Offset too large for record header
(996:31,386)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
jdbm.recman.RecordHeader.<init>(RecordHeader.java:83)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
jdbm.recman.PhysicalRowIdManager.allocNew(PhysicalRowIdManager.java:224)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
jdbm.recman.PhysicalRowIdManager.alloc(PhysicalRowIdManager.java:177)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
jdbm.recman.PhysicalRowIdManager.update(PhysicalRowIdManager.java:101)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
jdbm.recman.BaseRecordManager.update(BaseRecordManager.java:281)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
jdbm.recman.CacheRecordManager.updateCacheEntries(CacheRecordManager.java:417)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
jdbm.recman.CacheRecordManager.commit(CacheRecordManager.java:349)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable.sync(JdbmTable.java:976)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmPartition.sync(JdbmPartition.java:577)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.add(AbstractBTreePartition.java:871)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.shared.partition.DefaultPartitionNexus.add(DefaultPartitionNexus.java:361)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor$1.add(BaseInterceptor.java:107)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.journal.JournalInterceptor.add(JournalInterceptor.java:139)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.trigger.TriggerInterceptor.add(TriggerInterceptor.java:297)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.event.EventInterceptor.add(EventInterceptor.java:229)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.subtree.SubentryInterceptor.add(SubentryInterceptor.java:1018)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.collective.CollectiveAttributeInterceptor.add(CollectiveAttributeInterceptor.java:134)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.operational.OperationalAttributeInterceptor.add(OperationalAttributeInterceptor.java:310)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.schema.SchemaInterceptor.add(SchemaInterceptor.java:1109)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.hash.ConfigurableHashingInterceptor.add(ConfigurableHashingInterceptor.java:90)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.exception.ExceptionInterceptor.add(ExceptionInterceptor.java:188)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.admin.AdministrativePointInterceptor.add(AdministrativePointInterceptor.java:1189)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.authz.AciAuthorizationInterceptor.add(AciAuthorizationInterceptor.java:518)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.referral.ReferralInterceptor.add(ReferralInterceptor.java:247)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.authn.AuthenticationInterceptor.add(AuthenticationInterceptor.java:407)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:341)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.normalization.NormalizationInterceptor.add(NormalizationInterceptor.java:131)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.DefaultOperationManager.add(DefaultOperationManager.java:397)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.shared.DefaultCoreSession.add(DefaultCoreSession.java:251)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.core.shared.DefaultCoreSession.add(DefaultCoreSession.java:235)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.ldap.handlers.request.AddRequestHandler.handle(AddRequestHandler.java:57)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.ldap.handlers.request.AddRequestHandler.handle(AddRequestHandler.java:39)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:207)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:56)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:243)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:216)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:854)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:542)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1300(DefaultIoFilterChain.java:48)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:943)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:476)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:430)
INFO | jvm 1 | 2017/03/21 15:21:28 | at
java.lang.Thread.run(Thread.java:745)
STATUS | wrapper | 2017/03/22 00:32:58 | TERM trapped. Shutting down.
INFO | jvm 1 | 2017/03/22 00:32:58 | Error in WrapperListener.stop
callback. java.lang.Error: ERR_554 double get for block 996
INFO | jvm 1 | 2017/03/22 00:32:58 | java.lang.Error: ERR_554 double get
for block 996
INFO | jvm 1 | 2017/03/22 00:32:58 | at
jdbm.recman.RecordFile.get(RecordFile.java:185)
INFO | jvm 1 | 2017/03/22 00:32:58 | at
jdbm.recman.PhysicalRowIdManager.allocNew(PhysicalRowIdManager.java:202)
INFO | jvm 1 | 2017/03/22 00:32:58 | at
jdbm.recman.PhysicalRowIdManager.alloc(PhysicalRowIdManager.java:177)
INFO | jvm 1 | 2017/03/22 00:32:58 | at
jdbm.recman.PhysicalRowIdManager.update(PhysicalRowIdManager.java:101)
INFO | jvm 1 | 2017/03/22 00:32:58 | at
jdbm.recman.BaseRecordManager.update(BaseRecordManager.java:281)
INFO | jvm 1 | 2017/03/22 00:32:58 | at
jdbm.recman.CacheRecordManager.updateCacheEntries(CacheRecordManager.java:417)
INFO | jvm 1 | 2017/03/22 00:32:58 | at
jdbm.recman.CacheRecordManager.commit(CacheRecordManager.java:349)
INFO | jvm 1 | 2017/03/22 00:32:58 | at
org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable.sync(JdbmTable.java:976)
INFO | jvm 1 | 2017/03/22 00:32:58 | at
org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmPartition.sync(JdbmPartition.java:577)
INFO | jvm 1 | 2017/03/22 00:32:58 | at
org.apache.directory.server.core.shared.partition.DefaultPartitionNexus.sync(DefaultPartitionNexus.java:329)
INFO | jvm 1 | 2017/03/22 00:32:58 | at
org.apache.directory.server.core.DefaultDirectoryService.shutdown(DefaultDirectoryService.java:1290)
INFO | jvm 1 | 2017/03/22 00:32:58 | at
org.apache.directory.server.ApacheDsService.stop(ApacheDsService.java:626)
INFO | jvm 1 | 2017/03/22 00:32:58 | at
org.apache.directory.server.wrapper.ApacheDsTanukiWrapper.stop(ApacheDsTanukiWrapper.java:233)
INFO | jvm 1 | 2017/03/22 00:32:58 | at
org.tanukisoftware.wrapper.WrapperManager$13.run(WrapperManager.java:3134)
--
This message was sent by Atlassian JIRA
(v6.3.15#6346)
