Re: Error 1067: 'Process unexpectedly Terminated' when starting ApacheDS Service

[Kiran Ayyagari <ka...@apache.org>]

On Thu, Oct 1, 2015 at 1:52 AM, Robert Carter (ABS, Inc.) <
robert.carter@abs-consulting.com> wrote:

> When I rebooted my server after installing windows updates, I got this
> error message.  Upon further investigation into the log I found:
>
> ERROR [org.apache.directory.server.wrapper.ApacheDsTanukiWrapper] - Failed
> to start the service.
> org.apache.directory.api.ldap.model.exception.LdapOtherException: invalid
> stream header: 00000000
>
> Here is the log snippet.
>
> INFO   | jvm 1    | 2015/09/30 13:41:27 | [13:41:27] ERROR
> [org.apache.directory.server.wrapper.ApacheDsTanukiWrapper] - Failed to
> start the service.
> INFO   | jvm 1    | 2015/09/30 13:41:27 |
> org.apache.directory.api.ldap.model.exception.LdapOtherException: invalid
> stream header: 00000000
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:84)
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> org.apache.directory.server.core.DefaultDirectoryService.initialize(DefaultDirectoryService.java:1820)
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> org.apache.directory.server.core.DefaultDirectoryService.startup(DefaultDirectoryService.java:1248)
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> org.apache.directory.server.ApacheDsService.initDirectoryService(ApacheDsService.java:315)
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> org.apache.directory.server.ApacheDsService.start(ApacheDsService.java:179)
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> org.apache.directory.server.wrapper.ApacheDsTanukiWrapper.start(ApacheDsTanukiWrapper.java:72)
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> org.tanukisoftware.wrapper.WrapperManager$12.run(WrapperManager.java:2788)
> INFO   | jvm 1    | 2015/09/30 13:41:27 | Caused by:
> org.apache.directory.api.ldap.model.exception.LdapOtherException: invalid
> stream header: 00000000
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:84)
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> org.apache.directory.server.core.shared.partition.DefaultPartitionNexus.addContextPartition(DefaultPartitionNexus.java:829)
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> org.apache.directory.server.core.shared.partition.DefaultPartitionNexus.doInit(DefaultPartitionNexus.java:218)
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:79)
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       ... 6 more
> INFO   | jvm 1    | 2015/09/30 13:41:27 | Caused by:
> java.io.StreamCorruptedException: invalid stream header: 00000000
>
the backend is likely corrupted, we know that there is such an issue with
the current JDBM
backend (you may find references to such error or the infamous "double get"
error on this mailing list)

The only way is to maintain a backup of the server data in LDIF and
re-inject.

> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> java.io.ObjectInputStream.readStreamHeader(Unknown Source)
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> java.io.ObjectInputStream.<init>(Unknown Source)
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> jdbm.recman.TransactionManager.recover(TransactionManager.java:231)
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> jdbm.recman.TransactionManager.<init>(TransactionManager.java:120)
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> jdbm.recman.RecordFile.getTxnMgr(RecordFile.java:118)
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> jdbm.recman.BaseRecordManager.getTransactionManager(BaseRecordManager.java:135)
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmIndex.init(JdbmIndex.java:163)
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmPartition.convertAndInit(JdbmPartition.java:453)
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.setupSystemIndices(AbstractBTreePartition.java:353)
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> org.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.doInit(AbstractBTreePartition.java:519)
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> org.apache.directory.server.core.partition.impl.btree.jdbm.JdbmPartition.doInit(JdbmPartition.java:153)
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       at
> org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:79)
> INFO   | jvm 1    | 2015/09/30 13:41:27 |       ... 9 more
> INFO   | jvm 1    | 2015/09/30 13:41:27 | Wrapper Manager: ShutdownHook
> started
> INFO   | jvm 1    | 2015/09/30 13:41:27 | WrapperManager.stop(0) called by
> thread: Wrapper-Shutdown-Hook
> INFO   | jvm 1    | 2015/09/30 13:41:27 | [13:41:27] DEBUG
> [org.apache.directory.server.core.DefaultDirectoryService] - +++
> DirectoryService Shutdown required
> INFO   | jvm 1    | 2015/09/30 13:41:27 | Send a packet STOP : 0
> INFO   | jvm 1    | 2015/09/30 13:41:27 | Startup runner thread stopped.
> INFO   | jvm 1    | 2015/09/30 13:41:28 | Thread, Wrapper-Shutdown-Hook,
> handling the shutdown process.
> INFO   | jvm 1    | 2015/09/30 13:41:28 | shutdownJVM(0)
> Thread:Wrapper-Shutdown-Hook
> INFO   | jvm 1    | 2015/09/30 13:41:28 | Send a packet STOPPED : 0
> INFO   | jvm 1    | 2015/09/30 13:41:28 | Closing socket.
> INFO   | jvm 1    | 2015/09/30 13:41:29 | Wrapper Manager: ShutdownHook
> complete
> STATUS | wrapper  | 2015/09/30 13:41:29 | <-- Wrapper Stopped




-- 
Kiran Ayyagari
http://keydap.com