AnsweredAssumed Answered

Unknown Exception in Transaction - Invalid state MARKED_ROLL

Question asked by jrfchambers on Jul 16, 2008
Hey forum,

Just battling a start up issue and can not find anything in the forums that matches the error message being recorded in the alfresco.log file.

Setup
CentOS release 5 (Final)  2.6.18-53.1.14.el5 #1 SMP
Alfresco JVM - v1.6.0_05-b13; maximum heap size 506.313MB
Alfresco started (Community Network): Current version 2.1.0 (484) schema 64 - Installed version 2.1.0 (484) schema 64
mysql-5.0.22-2.2.el5_1.1
mysql-server-5.0.22-2.2.el5_1.1

Here is the log from a recent startup

09:54:57,581 WARN  [org.springframework.remoting.rmi.RmiRegistryFactoryBean] Could not detect RMI registry - creating new one
09:54:59,622 WARN  [org.alfresco.util.OpenOfficeConnectionTester] A connection to OpenOffice could not be established.
09:55:03,551 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Schema managed by database dialect org.hibernate.dialect.MySQLInnoDBDialect.
09:55:05,597 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] No changes were made to the schema.
09:55:08,331 INFO  [org.alfresco.repo.admin.ConfigurationChecker] The Alfresco root data directory ('dir.root') is: /opt/alfresco/alf_data
09:55:09,149 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] Checking for patches to apply …
09:55:09,503 INFO  [org.alfresco.repo.module.ModuleServiceImpl] Found 0 module(s).
09:55:10,012 ERROR [org.alfresco.smb.protocol.auth] No valid CIFS authentication combination available
09:55:10,012 ERROR [org.alfresco.smb.protocol.auth] Either enable Kerberos support or use an authentication component that supports MD4 hashed passwords
09:55:10,014 ERROR [org.alfresco.smb.protocol] CIFS server configuration error, Invalid CIFS authenticator configuration
org.alfresco.error.AlfrescoRuntimeException: Invalid CIFS authenticator configuration
        at org.alfresco.filesys.server.auth.EnterpriseCifsAuthenticator.initialize(EnterpriseCifsAuthenticator.java:348)
        at org.alfresco.filesys.server.config.ServerConfiguration.setAuthenticator(ServerConfiguration.java:3425)
        at org.alfresco.filesys.server.config.ServerConfiguration.processSecurityConfig(ServerConfiguration.java:2405)
        at org.alfresco.filesys.server.config.ServerConfiguration.init(ServerConfiguration.java:641)
        at org.alfresco.filesys.server.config.ServerConfiguration.onBootstrap(ServerConfiguration.java:4122)
        at org.alfresco.util.AbstractLifecycleBean.onApplicationEvent(AbstractLifecycleBean.java:62)
        at org.springframework.context.event.SimpleApplicationEventMulticaster$1.run(SimpleApplicationEventMulticaster.java:77)
        at org.springframework.core.task.SyncTaskExecutor.execute(SyncTaskExecutor.java:49)
        at org.springframework.context.event.SimpleApplicationEventMulticaster.multicastEvent(SimpleApplicationEventMulticaster.java:75)
        at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:241)
        at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:349)
        at org.springframework.web.context.support.AbstractRefreshableWebApplicationContext.refresh(AbstractRefreshableWebApplicationContext.java:156)
        at org.springframework.web.context.ContextLoader.createWebApplicationContext(ContextLoader.java:246)
        at org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:184)
        at org.springframework.web.context.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:49)
        at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3764)
        at org.apache.catalina.core.StandardContext.start(StandardContext.java:4216)
        at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:760)
        at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:740)
        at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:544)
        at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:825)
        at org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:714)
        at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:490)
        at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1138)
        at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:311)
        at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:120)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1022)
        at org.apache.catalina.core.StandardHost.start(StandardHost.java:736)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1014)
        at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:443)
        at org.apache.catalina.core.StandardService.start(StandardService.java:448)
        at org.apache.catalina.core.StandardServer.start(StandardServer.java:700)
        at org.apache.catalina.startup.Catalina.start(Catalina.java:552)
        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.apache.catalina.startup.Bootstrap.start(Bootstrap.java:295)
        at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:433)
09:55:10,041 INFO  [org.alfresco.service.descriptor.DescriptorService] Alfresco JVM - v1.6.0_05-b13; maximum heap size 506.313MB
09:55:10,042 INFO  [org.alfresco.service.descriptor.DescriptorService] Alfresco started (Community Network): Current version 2.1.0 (484) schema 64 - Installed version 2.1.0 (484) schema 64
09:55:10,536 ERROR [org.springframework.web.context.ContextLoader] Context initialization failed
org.alfresco.error.AlfrescoRuntimeException: Unknown Exception in Transaction.
        at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:292)
        at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:155)
        at org.alfresco.repo.workflow.WorkflowInterpreter$1.doWork(WorkflowInterpreter.java:291)
        at org.alfresco.repo.workflow.WorkflowInterpreter$1.doWork(WorkflowInterpreter.java:282)
        at org.alfresco.repo.security.authentication.AuthenticationUtil.runAs(AuthenticationUtil.java:260)
        at org.alfresco.repo.workflow.WorkflowInterpreter.interpretCommand(WorkflowInterpreter.java:280)
        at org.alfresco.repo.workflow.WorkflowInterpreter.onBootstrap(WorkflowInterpreter.java:149)
        at org.alfresco.util.AbstractLifecycleBean.onApplicationEvent(AbstractLifecycleBean.java:62)
        at org.springframework.context.event.SimpleApplicationEventMulticaster$1.run(SimpleApplicationEventMulticaster.java:77)
        at org.springframework.core.task.SyncTaskExecutor.execute(SyncTaskExecutor.java:49)
        at org.springframework.context.event.SimpleApplicationEventMulticaster.multicastEvent(SimpleApplicationEventMulticaster.java:75)
        at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:241)
        at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:349)
        at org.springframework.web.context.support.AbstractRefreshableWebApplicationContext.refresh(AbstractRefreshableWebApplicationContext.java:156)
        at org.springframework.web.context.ContextLoader.createWebApplicationContext(ContextLoader.java:246)
        at org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:184)
        at org.springframework.web.context.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:49)
        at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3764)
        at org.apache.catalina.core.StandardContext.start(StandardContext.java:4216)
        at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:760)
        at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:740)
        at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:544)
        at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:825)
        at org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:714)
        at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:490)
        at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1138)
        at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:311)
        at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:120)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1022)
        at org.apache.catalina.core.StandardHost.start(StandardHost.java:736)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1014)
        at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:443)
        at org.apache.catalina.core.StandardService.start(StandardService.java:448)
        at org.apache.catalina.core.StandardServer.start(StandardServer.java:700)
        at org.apache.catalina.startup.Catalina.start(Catalina.java:552)
        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.apache.catalina.startup.Bootstrap.start(Bootstrap.java:295)
        at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:433)
Caused by: javax.transaction.RollbackException: Transaction didn't commit: Failed to prepare: requires rollback
        at org.alfresco.util.transaction.SpringAwareUserTransaction.commit(SpringAwareUserTransaction.java:430)
        at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:239)
        … 40 more
Caused by: org.alfresco.repo.search.IndexerException: Failed to prepare: requires rollback
        at org.alfresco.repo.search.impl.lucene.AbstractLuceneIndexerAndSearcherFactory.prepare(AbstractLuceneIndexerAndSearcherFactory.java:684)
        at org.alfresco.repo.transaction.AlfrescoTransactionSupport$TransactionSynchronizationImpl.beforeCommit(AlfrescoTransactionSupport.java:634)
        at org.springframework.transaction.support.TransactionSynchronizationUtils.triggerBeforeCommit(TransactionSynchronizationUtils.java:48)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.triggerBeforeCommit(AbstractPlatformTransactionManager.java:821)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:637)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:624)
        at org.springframework.transaction.interceptor.TransactionAspectSupport.commitTransactionAfterReturning(TransactionAspectSupport.java:307)
        at org.alfresco.util.transaction.SpringAwareUserTransaction.commit(SpringAwareUserTransaction.java:420)
        … 41 more
Caused by: org.alfresco.repo.search.IndexerException: Invalid state MARKED_ROLLBACK
        at org.alfresco.repo.search.impl.lucene.index.IndexInfo.getTransition(IndexInfo.java:1171)
        at org.alfresco.repo.search.impl.lucene.index.IndexInfo.setStatus(IndexInfo.java:1099)
        at org.alfresco.repo.search.impl.lucene.AbstractLuceneBase.setStatus(AbstractLuceneBase.java:256)
        at org.alfresco.repo.search.impl.lucene.AbstractLuceneIndexerImpl.setRollbackOnly(AbstractLuceneIndexerImpl.java:549)
        at org.alfresco.repo.search.impl.lucene.AbstractLuceneIndexerImpl.prepare(AbstractLuceneIndexerImpl.java:484)
        at org.alfresco.repo.search.impl.lucene.AbstractLuceneIndexerAndSearcherFactory.prepare(AbstractLuceneIndexerAndSearcherFactory.java:679)
        … 48 more
09:55:10,538 ERROR [org.apache.catalina.core.ContainerBase.[Catalina].[localhost].[/alfresco]] Exception sending context initialized event to listener instance of class org.springframework.web.context.ContextLoaderListener
org.alfresco.error.AlfrescoRuntimeException: Unknown Exception in Transaction.
        at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:292)
        at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:155)
        at org.alfresco.repo.workflow.WorkflowInterpreter$1.doWork(WorkflowInterpreter.java:291)
        at org.alfresco.repo.workflow.WorkflowInterpreter$1.doWork(WorkflowInterpreter.java:282)
        at org.alfresco.repo.security.authentication.AuthenticationUtil.runAs(AuthenticationUtil.java:260)
        at org.alfresco.repo.workflow.WorkflowInterpreter.interpretCommand(WorkflowInterpreter.java:280)
        at org.alfresco.repo.workflow.WorkflowInterpreter.onBootstrap(WorkflowInterpreter.java:149)
        at org.alfresco.util.AbstractLifecycleBean.onApplicationEvent(AbstractLifecycleBean.java:62)
        at org.springframework.context.event.SimpleApplicationEventMulticaster$1.run(SimpleApplicationEventMulticaster.java:77)
        at org.springframework.core.task.SyncTaskExecutor.execute(SyncTaskExecutor.java:49)
        at org.springframework.context.event.SimpleApplicationEventMulticaster.multicastEvent(SimpleApplicationEventMulticaster.java:75)
        at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:241)
        at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:349)
        at org.springframework.web.context.support.AbstractRefreshableWebApplicationContext.refresh(AbstractRefreshableWebApplicationContext.java:156)
        at org.springframework.web.context.ContextLoader.createWebApplicationContext(ContextLoader.java:246)
        at org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:184)
        at org.springframework.web.context.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:49)
        at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3764)
        at org.apache.catalina.core.StandardContext.start(StandardContext.java:4216)
        at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:760)
        at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:740)
        at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:544)
        at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:825)
        at org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:714)
        at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:490)
        at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1138)
        at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:311)
        at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:120)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1022)
        at org.apache.catalina.core.StandardHost.start(StandardHost.java:736)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1014)
        at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:443)
        at org.apache.catalina.core.StandardService.start(StandardService.java:448)
        at org.apache.catalina.core.StandardServer.start(StandardServer.java:700)
        at org.apache.catalina.startup.Catalina.start(Catalina.java:552)
        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.apache.catalina.startup.Bootstrap.start(Bootstrap.java:295)
        at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:433)
Caused by: javax.transaction.RollbackException: Transaction didn't commit: Failed to prepare: requires rollback
        at org.alfresco.util.transaction.SpringAwareUserTransaction.commit(SpringAwareUserTransaction.java:430)
        at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:239)
        … 40 more
Caused by: org.alfresco.repo.search.IndexerException: Failed to prepare: requires rollback
        at org.alfresco.repo.search.impl.lucene.AbstractLuceneIndexerAndSearcherFactory.prepare(AbstractLuceneIndexerAndSearcherFactory.java:684)
        at org.alfresco.repo.transaction.AlfrescoTransactionSupport$TransactionSynchronizationImpl.beforeCommit(AlfrescoTransactionSupport.java:634)
        at org.springframework.transaction.support.TransactionSynchronizationUtils.triggerBeforeCommit(TransactionSynchronizationUtils.java:48)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.triggerBeforeCommit(AbstractPlatformTransactionManager.java:821)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:637)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:624)
        at org.springframework.transaction.interceptor.TransactionAspectSupport.commitTransactionAfterReturning(TransactionAspectSupport.java:307)
        at org.alfresco.util.transaction.SpringAwareUserTransaction.commit(SpringAwareUserTransaction.java:420)
        … 41 more
Caused by: org.alfresco.repo.search.IndexerException: Invalid state MARKED_ROLLBACK
        at org.alfresco.repo.search.impl.lucene.index.IndexInfo.getTransition(IndexInfo.java:1171)
        at org.alfresco.repo.search.impl.lucene.index.IndexInfo.setStatus(IndexInfo.java:1099)
        at org.alfresco.repo.search.impl.lucene.AbstractLuceneBase.setStatus(AbstractLuceneBase.java:256)
        at org.alfresco.repo.search.impl.lucene.AbstractLuceneIndexerImpl.setRollbackOnly(AbstractLuceneIndexerImpl.java:549)
        at org.alfresco.repo.search.impl.lucene.AbstractLuceneIndexerImpl.prepare(AbstractLuceneIndexerImpl.java:484)
        at org.alfresco.repo.search.impl.lucene.AbstractLuceneIndexerAndSearcherFactory.prepare(AbstractLuceneIndexerAndSearcherFactory.java:679)
        … 48 more

it looks like possibly an issue with an index.  when debugging is increased,


09:12:24,688 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
09:12:24,689 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 0.0083363 ms
09:12:24,689 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
09:12:24,689 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for WRITE lock  - main
09:12:24,689 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT WRITE LOCK  - main -  in 6.082E-4 ms
09:12:24,689 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Start Index 6dd1cb73-5280-11dd-88a2-512e2a4e683e state = PREPARING
09:12:24,689 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=1efe0572-379d-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=4767 Deletions=0
2         Name=0acb951c-38d0-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=310 Deletions=0
3         Name=240aa5d1-4166-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=107 Deletions=0
4         Name=e624789a-465f-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=52 Deletions=0
5         Name=010b4408-4d2f-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=45 Deletions=0
6         Name=1bff4a55-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
7         Name=43594bb2-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
8         Name=4f327015-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
9         Name=582c9072-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
10         Name=ef90c44d-1c0c-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
11         Name=6dd1cb73-5280-11dd-88a2-512e2a4e683e Type=DELTA Status=ACTIVE Docs=0 Deletions=0

09:12:24,689 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] End Index 6dd1cb73-5280-11dd-88a2-512e2a4e683e state = PREPARING
09:12:24,689 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=1efe0572-379d-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=4767 Deletions=0
2         Name=0acb951c-38d0-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=310 Deletions=0
3         Name=240aa5d1-4166-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=107 Deletions=0
4         Name=e624789a-465f-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=52 Deletions=0
5         Name=010b4408-4d2f-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=45 Deletions=0
6         Name=1bff4a55-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
7         Name=43594bb2-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
8         Name=4f327015-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
9         Name=582c9072-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
10         Name=ef90c44d-1c0c-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
11         Name=6dd1cb73-5280-11dd-88a2-512e2a4e683e Type=DELTA Status=PREPARING Docs=0 Deletions=0

09:12:24,689 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
09:12:24,690 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 5.527E-4 ms
09:12:24,690 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED WRITE LOCK  - main
09:12:24,690 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
09:12:24,690 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
09:12:24,690 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 5.06E-4 ms
09:12:24,690 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] main: Reader MainReader - increment - ref count is 1        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@af7a1f
09:12:24,690 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Main index reader references = 1
09:12:24,690 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
09:12:24,690 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
09:12:24,690 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 5.61E-4 ms
09:12:24,690 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
09:12:24,691 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
09:12:24,691 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 5.278E-4 ms
09:12:24,691 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
09:12:24,702 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
09:12:24,702 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 0.0010083 ms
09:12:24,702 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
09:12:24,702 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for WRITE lock  - main
09:12:24,702 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT WRITE LOCK  - main -  in 6.103E-4 ms
09:12:24,703 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Start Index 6dd1cb73-5280-11dd-88a2-512e2a4e683e state = ROLLINGBACK
09:12:24,703 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=1efe0572-379d-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=4767 Deletions=0
2         Name=0acb951c-38d0-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=310 Deletions=0
3         Name=240aa5d1-4166-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=107 Deletions=0
4         Name=e624789a-465f-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=52 Deletions=0
5         Name=010b4408-4d2f-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=45 Deletions=0
6         Name=1bff4a55-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
7         Name=43594bb2-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
8         Name=4f327015-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
9         Name=582c9072-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
10         Name=ef90c44d-1c0c-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
11         Name=6dd1cb73-5280-11dd-88a2-512e2a4e683e Type=DELTA Status=PREPARING Docs=0 Deletions=0

09:12:24,751 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] End Index 6dd1cb73-5280-11dd-88a2-512e2a4e683e state = ROLLINGBACK
09:12:24,751 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=1efe0572-379d-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=4767 Deletions=0
2         Name=0acb951c-38d0-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=310 Deletions=0
3         Name=240aa5d1-4166-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=107 Deletions=0
4         Name=e624789a-465f-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=52 Deletions=0
5         Name=010b4408-4d2f-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=45 Deletions=0
6         Name=1bff4a55-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
7         Name=43594bb2-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
8         Name=4f327015-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
9         Name=582c9072-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
10         Name=ef90c44d-1c0c-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
11         Name=6dd1cb73-5280-11dd-88a2-512e2a4e683e Type=DELTA Status=ROLLINGBACK Docs=0 Deletions=0

09:12:24,751 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
09:12:24,751 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 7.022E-4 ms
09:12:24,751 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED WRITE LOCK  - main
09:12:24,751 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
09:12:24,751 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
09:12:24,752 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 5.21E-4 ms
09:12:24,752 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
09:12:24,752 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for WRITE lock  - main
09:12:24,752 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT WRITE LOCK  - main -  in 4.542E-4 ms
09:12:24,752 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Start Index 6dd1cb73-5280-11dd-88a2-512e2a4e683e state = ROLLEDBACK
09:12:24,752 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=1efe0572-379d-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=4767 Deletions=0
2         Name=0acb951c-38d0-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=310 Deletions=0
3         Name=240aa5d1-4166-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=107 Deletions=0
4         Name=e624789a-465f-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=52 Deletions=0
5         Name=010b4408-4d2f-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=45 Deletions=0
6         Name=1bff4a55-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
7         Name=43594bb2-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
8         Name=4f327015-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
9         Name=582c9072-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
10         Name=ef90c44d-1c0c-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
11         Name=6dd1cb73-5280-11dd-88a2-512e2a4e683e Type=DELTA Status=ROLLINGBACK Docs=0 Deletions=0

09:12:24,761 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] End Index 6dd1cb73-5280-11dd-88a2-512e2a4e683e state = ROLLEDBACK
09:12:24,761 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=1efe0572-379d-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=4767 Deletions=0
2         Name=0acb951c-38d0-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=310 Deletions=0
3         Name=240aa5d1-4166-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=107 Deletions=0
4         Name=e624789a-465f-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=52 Deletions=0
5         Name=010b4408-4d2f-11dd-8e21-dd23fd25bcbd Type=INDEX Status=COMMITTED Docs=45 Deletions=0
6         Name=1bff4a55-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
7         Name=43594bb2-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
8         Name=4f327015-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
9         Name=582c9072-4d2f-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
10         Name=ef90c44d-1c0c-11dd-8e21-dd23fd25bcbd Type=DELTA Status=COMMITTED Docs=1 Deletions=1
11         Name=6dd1cb73-5280-11dd-88a2-512e2a4e683e Type=DELTA Status=ROLLEDBACK Docs=0 Deletions=0

09:12:24,762 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
09:12:24,762 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 5.862E-4 ms
09:12:24,762 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED WRITE LOCK  - main
09:12:24,762 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
09:12:24,763 DEBUG [org.alfresco.util.transaction.SpringAwareUserTransaction] Transaction didn't commit
org.alfresco.repo.search.IndexerException: Failed to prepare: requires rollback
        at org.alfresco.repo.search.impl.lucene.AbstractLuceneIndexerAndSearcherFactory.prepare(AbstractLuceneIndexerAndSearcherFactory.java:684)
        at org.alfresco.repo.transaction.AlfrescoTransactionSupport$TransactionSynchronizationImpl.beforeCommit(AlfrescoTransactionSupport.java:634)
        at org.springframework.transaction.support.TransactionSynchronizationUtils.triggerBeforeCommit(TransactionSynchronizationUtils.java:48)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.triggerBeforeCommit(AbstractPlatformTransactionManager.java:821)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:637)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:624)
        at org.springframework.transaction.interceptor.TransactionAspectSupport.commitTransactionAfterReturning(TransactionAspectSupport.java:307)
        at org.alfresco.util.transaction.SpringAwareUserTransaction.commit(SpringAwareUserTransaction.java:420)
        at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:239)
        at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:155)
        at org.alfresco.repo.workflow.WorkflowInterpreter$1.doWork(WorkflowInterpreter.java:291)
        at org.alfresco.repo.workflow.WorkflowInterpreter$1.doWork(WorkflowInterpreter.java:282)
        at org.alfresco.repo.security.authentication.AuthenticationUtil.runAs(AuthenticationUtil.java:260)
        at org.alfresco.repo.workflow.WorkflowInterpreter.interpretCommand(WorkflowInterpreter.java:280)
        at org.alfresco.repo.workflow.WorkflowInterpreter.onBootstrap(WorkflowInterpreter.java:149)
        at org.alfresco.util.AbstractLifecycleBean.onApplicationEvent(AbstractLifecycleBean.java:62)
        at org.springframework.context.event.SimpleApplicationEventMulticaster$1.run(SimpleApplicationEventMulticaster.java:77)
        at org.springframework.core.task.SyncTaskExecutor.execute(SyncTaskExecutor.java:49)
        at org.springframework.context.event.SimpleApplicationEventMulticaster.multicastEvent(SimpleApplicationEventMulticaster.java:75)
        at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:241)
        at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:349)
        at org.springframework.web.context.support.AbstractRefreshableWebApplicationContext.refresh(AbstractRefreshableWebApplicationContext.java:156)
        at org.springframework.web.context.ContextLoader.createWebApplicationContext(ContextLoader.java:246)
        at org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:184)
        at org.springframework.web.context.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:49)
        at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3764)
        at org.apache.catalina.core.StandardContext.start(StandardContext.java:4216)
        at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:760)
        at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:740)
        at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:544)
        at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:825)
        at org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:714)
        at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:490)
        at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1138)
        at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:311)
        at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:120)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1022)
        at org.apache.catalina.core.StandardHost.start(StandardHost.java:736)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1014)
        at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:443)
        at org.apache.catalina.core.StandardService.start(StandardService.java:448)
        at org.apache.catalina.core.StandardServer.start(StandardServer.java:700)
        at org.apache.catalina.startup.Catalina.start(Catalina.java:552)
        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.apache.catalina.startup.Bootstrap.start(Bootstrap.java:295)
        at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:433)
Caused by: org.alfresco.repo.search.IndexerException: Invalid state MARKED_ROLLBACK
        at org.alfresco.repo.search.impl.lucene.index.IndexInfo.getTransition(IndexInfo.java:1171)
        at org.alfresco.repo.search.impl.lucene.index.IndexInfo.setStatus(IndexInfo.java:1099)
        at org.alfresco.repo.search.impl.lucene.AbstractLuceneBase.setStatus(AbstractLuceneBase.java:256)
        at org.alfresco.repo.search.impl.lucene.AbstractLuceneIndexerImpl.setRollbackOnly(AbstractLuceneIndexerImpl.java:549)
        at org.alfresco.repo.search.impl.lucene.AbstractLuceneIndexerImpl.prepare(AbstractLuceneIndexerImpl.java:484)
        at org.alfresco.repo.search.impl.lucene.AbstractLuceneIndexerAndSearcherFactory.prepare(AbstractLuceneIndexerAndSearcherFactory.java:679)
        … 48 more
09:12:24,765 ERROR [org.springframework.web.context.ContextLoader] Context initialization failed
org.alfresco.error.AlfrescoRuntimeException: Unknown Exception in Transaction.


Right now my best guess is that it has something to do with this index: 6dd1cb73-5280-11dd-88a2-512e2a4e683e
but as far as I can tell, these are the closest matches

/opt/alfresco/tomcat/temp/Alfresco/2008/7/15/9/12/6cedacfa-5280-11dd-88a2-512e2a4e683e.bin
/var/db/alf_data/contentstore/2008/7/15/9/12/6e00cab6-5280-11dd-88a2-512e2a4e683e.bin
/var/db/alf_data/lucene-indexes/system/system/6cc84aa9-5280-11dd-88a2-512e2a4e683e
/var/db/alf_data/lucene-indexes/system/system/6cc84aa9-5280-11dd-88a2-512e2a4e683e/IndexInfoDeletions
/var/db/alf_data/lucene-indexes/system/system/6cc84aa9-5280-11dd-88a2-512e2a4e683e/_0.cfs
/var/db/alf_data/lucene-indexes/system/system/6cc84aa9-5280-11dd-88a2-512e2a4e683e/segments.gen
/var/db/alf_data/lucene-indexes/system/system/6cc84aa9-5280-11dd-88a2-512e2a4e683e/segments_3

Is there anything I can do to force the rollback or clean out this one particular index?  is there something else I missed?

Thanks,
Jason.

Outcomes