5 Replies Latest reply on Jul 4, 2011 6:36 AM by ronnys

    Journaling NPE during startup

    ronnys

      Hi,

       

      I just got an NPE while starting up HornetQ 2.2.2 (r10449; see http://community.jboss.org/thread/164768), HornetQ didn't came up properly afterwards, I had to delete the journaling files to bring it up. Could you please have a look?

       

      [WrapperSimpleAppMain] 09:58:40,634 INFO [org.hornetq.integration.bootstrap.HornetQBootstrapServer]  Starting HornetQ Server

      [WrapperSimpleAppMain] 09:58:41,978 INFO [org.hornetq.core.server.impl.HornetQServerImpl]  live server is starting with configuration HornetQ Configuration (clustered=false,backup=false,sharedStore=true,journalDirectory=/var/hornetq/data/journal,bindingsDirectory=/var/hornetq/data/bindings,largeMessagesDirectory=/var/hornetq/data/large-messages,pagingDirectory=/var/hornetq/data/paging)

      [WrapperSimpleAppMain] 09:58:41,978 INFO [org.hornetq.core.server.impl.HornetQServerImpl]  Waiting to obtain live lock

      [WrapperSimpleAppMain] 09:58:42,090 INFO [org.hornetq.core.persistence.impl.journal.JournalStorageManager]  Using AIO Journal

      [WrapperSimpleAppMain] 09:58:42,090 WARNING [org.hornetq.core.server.impl.HornetQServerImpl]  Security risk! It has been detected that the cluster admin user and password have not been changed from the installation default. Please see the HornetQ user guide, cluster chapter, for instructions on how to do this.

      [WrapperSimpleAppMain] 09:58:42,314 WARNING [org.hornetq.core.paging.impl.PageImpl]  Page file had incomplete records at position 4091 at record number 2

      [WrapperSimpleAppMain] 09:58:42,482 INFO [org.hornetq.core.server.impl.AIOFileLockNodeManager]  Waiting to obtain live lock

      [WrapperSimpleAppMain] 09:58:42,482 INFO [org.hornetq.core.server.impl.AIOFileLockNodeManager]  Live Server Obtained live lock

      [WrapperSimpleAppMain] 09:58:42,482 WARNING [org.hornetq.core.paging.impl.PageImpl]  Page file had incomplete records at position 4091 at record number 2

      [WrapperSimpleAppMain] 09:58:42,706 WARNING [org.hornetq.core.journal.impl.JournalImpl]  Temporary files were left unnatended after a crash on journal directory, deleting invalid files now

      [WrapperSimpleAppMain] 09:58:42,706 WARNING [org.hornetq.core.journal.impl.JournalImpl]  Deleting unnatended file hornetq-data-791.hq.tmp

      [WrapperSimpleAppMain] 09:58:42,706 WARNING [org.hornetq.core.journal.impl.JournalImpl]  Deleting unnatended file hornetq-data-792.hq.tmp

      [WrapperSimpleAppMain] 09:58:50,498 INFO [org.hornetq.core.persistence.impl.journal.JournalStorageManager]  79% loaded

      [WrapperSimpleAppMain] 09:58:50,722 WARNING [org.hornetq.core.persistence.impl.journal.JournalStorageManager]  Failed to remove reference for 2183683803

      [WrapperSimpleAppMain] 09:58:50,722 SEVERE [org.hornetq.core.server.impl.HornetQServerImpl]  Failure in initialisation

      java.lang.NullPointerException

          at org.hornetq.core.persistence.impl.journal.JournalStorageManager.loadPreparedTransactions(JournalStorageManager.java:1877)

          at org.hornetq.core.persistence.impl.journal.JournalStorageManager.loadMessageJournal(JournalStorageManager.java:1168)

          at org.hornetq.core.server.impl.HornetQServerImpl.loadJournals(HornetQServerImpl.java:1614)

          at org.hornetq.core.server.impl.HornetQServerImpl.initialisePart2(HornetQServerImpl.java:1464)

          at org.hornetq.core.server.impl.HornetQServerImpl.access$100(HornetQServerImpl.java:132)

          at org.hornetq.core.server.impl.HornetQServerImpl$SharedStoreLiveActivation.run(HornetQServerImpl.java:356)

          at org.hornetq.core.server.impl.HornetQServerImpl.start(HornetQServerImpl.java:570)

          at org.hornetq.jms.server.impl.JMSServerManagerImpl.start(JMSServerManagerImpl.java:261)

          at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

          at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)

          at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)

          at java.lang.reflect.Method.invoke(Method.java:597)

          at org.jboss.reflect.plugins.introspection.ReflectionUtils.invoke(ReflectionUtils.java:59)

          at org.jboss.reflect.plugins.introspection.ReflectMethodInfoImpl.invoke(ReflectMethodInfoImpl.java:150)

          at org.jboss.joinpoint.plugins.BasicMethodJoinPoint.dispatch(BasicMethodJoinPoint.java:66)

          at org.jboss.kernel.plugins.dependency.KernelControllerContextAction$JoinpointDispatchWrapper.execute(KernelControllerContextAction.java:241)

          at org.jboss.kernel.plugins.dependency.ExecutionWrapper.execute(ExecutionWrapper.java:47)

          at org.jboss.kernel.plugins.dependency.KernelControllerContextAction.dispatchExecutionWrapper(KernelControllerContextAction.java:109)

          at org.jboss.kernel.plugins.dependency.KernelControllerContextAction.dispatchJoinPoint(KernelControllerContextAction.java:70)

          at org.jboss.kernel.plugins.dependency.LifecycleAction.installActionInternal(LifecycleAction.java:221)

          at org.jboss.kernel.plugins.dependency.InstallsAwareAction.installAction(InstallsAwareAction.java:54)

          at org.jboss.kernel.plugins.dependency.InstallsAwareAction.installAction(InstallsAwareAction.java:42)

          at org.jboss.dependency.plugins.action.SimpleControllerContextAction.simpleInstallAction(SimpleControllerContextAction.java:62)

          at org.jboss.dependency.plugins.action.AccessControllerContextAction.install(AccessControllerContextAction.java:71)

          at org.jboss.dependency.plugins.AbstractControllerContextActions.install(AbstractControllerContextActions.java:51)

          at org.jboss.dependency.plugins.AbstractControllerContext.install(AbstractControllerContext.java:348)

          at org.jboss.dependency.plugins.AbstractController.install(AbstractController.java:1631)

          at org.jboss.dependency.plugins.AbstractController.incrementState(AbstractController.java:934)

          at org.jboss.dependency.plugins.AbstractController.resolveContexts(AbstractController.java:1082)

          at org.jboss.dependency.plugins.AbstractController.resolveContexts(AbstractController.java:984)

          at org.jboss.dependency.plugins.AbstractController.install(AbstractController.java:774)

          at org.jboss.dependency.plugins.AbstractController.install(AbstractController.java:540)

          at org.jboss.kernel.plugins.deployment.AbstractKernelDeployer.deployBean(AbstractKernelDeployer.java:319)

          at org.jboss.kernel.plugins.deployment.AbstractKernelDeployer.deployBeans(AbstractKernelDeployer.java:297)

          at org.jboss.kernel.plugins.deployment.AbstractKernelDeployer.deploy(AbstractKernelDeployer.java:130)

          at org.jboss.kernel.plugins.deployment.xml.BeanXMLDeployer.deploy(BeanXMLDeployer.java:96)

          at org.hornetq.integration.bootstrap.HornetQBootstrapServer.deploy(HornetQBootstrapServer.java:236)

          at org.hornetq.integration.bootstrap.HornetQBootstrapServer.deploy(HornetQBootstrapServer.java:206)

          at org.hornetq.integration.bootstrap.HornetQBootstrapServer.bootstrap(HornetQBootstrapServer.java:155)

          at org.jboss.kernel.plugins.bootstrap.AbstractBootstrap.run(AbstractBootstrap.java:83)

          at org.hornetq.integration.bootstrap.HornetQBootstrapServer.run(HornetQBootstrapServer.java:116)

          at org.hornetq.integration.bootstrap.HornetQBootstrapServer.main(HornetQBootstrapServer.java:73)

          at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

          at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)

          at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)

          at java.lang.reflect.Method.invoke(Method.java:597)

          at org.tanukisoftware.wrapper.WrapperSimpleApp.run(WrapperSimpleApp.java:244)

          at java.lang.Thread.run(Thread.java:619)

       

      It might be that some files are corrupt as the disk ran full recently (the disk usage was back to 50% when the error above as thrown). There were no recent configuration changes (configuration attached). I've a copy of the data files, so if there is anything I could check there for you please let me know.

       

      Best regards,

      Ronny