cancel
Showing results for 
Search instead for 
Did you mean: 

Rebuilding indexes does not work 'as fine as it did

miguel_gil_mart
Champ in-the-making
Champ in-the-making
Hello.
I have been "losing users" for a few of days. When I tried to log in as an user, Alfresco said that this user did not exist. I rebuilt indexes and Alfresco worked right.
Yesterday I tried to log in as admin and system rejected me. I tried to log in as another user and it was impossible. Some others users still work. I rebuild indexes and alfresco.log says:


07:20:46,055 INFO  [org.alfresco.service.descriptor.DescriptorService] Alfresco started (Community Network): Current version 2.0.0 (build-185) schema 38 - Installed version 2.0.0 (build-185) schema 38
07:21:37,832 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent] Index recovery started: 52,769 transactions.
07:34:01,812 ERROR [org.alfresco.util.transaction.SpringAwareUserTransaction] Transaction didn't commit
java.lang.NullPointerException
        at org.alfresco.repo.domain.PropertyValue$ValueType.convert(PropertyValue.java:301)
        at org.alfresco.repo.domain.PropertyValue.getValue(PropertyValue.java:727)
        at org.alfresco.repo.node.AbstractNodeServiceImpl.makeSerializableValue(AbstractNodeServiceImpl.java:689)
        at org.alfresco.repo.node.db.DbNodeServiceImpl.getPropertiesImpl(DbNodeServiceImpl.java:860)
        at org.alfresco.repo.node.db.DbNodeServiceImpl.getProperties(DbNodeServiceImpl.java:845)
        at sun.reflect.GeneratedMethodAccessor161.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:585)
        at org.alfresco.repo.service.StoreRedirectorProxyFactory$RedirectorInvocationHandler.invoke(StoreRedirectorProxyFactory.java:221)
        at $Proxy2.getProperties(Unknown Source)
        at org.alfresco.repo.search.impl.lucene.LuceneIndexerImpl2.createDocuments(LuceneIndexerImpl2.java:1250)
        at org.alfresco.repo.search.impl.lucene.LuceneIndexerImpl2.indexImpl(LuceneIndexerImpl2.java:1170)
        at org.alfresco.repo.search.impl.lucene.LuceneIndexerImpl2.indexImpl(LuceneIndexerImpl2.java:1158)
        at org.alfresco.repo.search.impl.lucene.LuceneIndexerImpl2.flushPending(LuceneIndexerImpl2.java:961)
        at org.alfresco.repo.search.impl.lucene.LuceneIndexerImpl2.prepare(LuceneIndexerImpl2.java:732)
        at org.alfresco.repo.search.impl.lucene.LuceneIndexerAndSearcherFactory2.prepare(LuceneIndexerAndSearcherFactory2.java:706)
        at org.alfresco.repo.transaction.AlfrescoTransactionSupport$TransactionSynchronizationImpl.beforeCommit(AlfrescoTransactionSupport.java:599)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.triggerBeforeCommit(AbstractPlatformTransactionManager.java:657)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:482)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:469)
        at org.springframework.transaction.interceptor.TransactionAspectSupport.doCommitTransactionAfterReturning(TransactionAspectSupport.java:266)
        at org.alfresco.util.transaction.SpringAwareUserTransaction.commit(SpringAwareUserTransaction.java:405)
        at org.alfresco.repo.transaction.TransactionUtil.executeInTransaction(TransactionUtil.java:186)
        at org.alfresco.repo.transaction.TransactionUtil.executeInNonPropagatingUserTransaction(TransactionUtil.java:134)
        at org.alfresco.repo.node.index.FullIndexRecoveryComponent.reindexTransaction(FullIndexRecoveryComponent.java:283)
        at org.alfresco.repo.node.index.FullIndexRecoveryComponent.performFullRecovery(FullIndexRecoveryComponent.java:206)
        at org.alfresco.repo.node.index.FullIndexRecoveryComponent.reindexImpl(FullIndexRecoveryComponent.java:165)
        at org.alfresco.repo.node.index.AbstractReindexComponent$1.doWork(AbstractReindexComponent.java:217)
        at org.alfresco.repo.transaction.TransactionUtil.executeInTransaction(TransactionUtil.java:176)
        at org.alfresco.repo.transaction.TransactionUtil.executeInUserTransaction(TransactionUtil.java:82)
        at org.alfresco.repo.node.index.AbstractReindexComponent.reindex(AbstractReindexComponent.java:221)
        at org.alfresco.repo.node.index.IndexRecoveryJob.execute(IndexRecoveryJob.java:31)
        at org.quartz.core.JobRunShell.run(JobRunShell.java:202)
        at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:529)
07:34:01,815 ERROR [org.quartz.core.JobRunShell] Job DEFAULT.org.springframework.scheduling.quartz.JobDetailBean#17748d3 threw an unhandled Exception:
org.alfresco.error.AlfrescoRuntimeException: Unexpected rollback of exception:
Transaction didn't commit: null
        at org.alfresco.repo.transaction.TransactionUtil.executeInTransaction(TransactionUtil.java:192)
        at org.alfresco.repo.transaction.TransactionUtil.executeInNonPropagatingUserTransaction(TransactionUtil.java:134)
        at org.alfresco.repo.node.index.FullIndexRecoveryComponent.reindexTransaction(FullIndexRecoveryComponent.java:283)
        at org.alfresco.repo.node.index.FullIndexRecoveryComponent.performFullRecovery(FullIndexRecoveryComponent.java:206)
        at org.alfresco.repo.node.index.FullIndexRecoveryComponent.reindexImpl(FullIndexRecoveryComponent.java:165)
        at org.alfresco.repo.node.index.AbstractReindexComponent$1.doWork(AbstractReindexComponent.java:217)
        at org.alfresco.repo.transaction.TransactionUtil.executeInTransaction(TransactionUtil.java:176)
        at org.alfresco.repo.transaction.TransactionUtil.executeInUserTransaction(TransactionUtil.java:82)
        at org.alfresco.repo.node.index.AbstractReindexComponent.reindex(AbstractReindexComponent.java:221)
        at org.alfresco.repo.node.index.IndexRecoveryJob.execute(IndexRecoveryJob.java:31)
        at org.quartz.core.JobRunShell.run(JobRunShell.java:202)
        at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:529)
Caused by: javax.transaction.RollbackException: Transaction didn't commit: null
        at org.alfresco.util.transaction.SpringAwareUserTransaction.commit(SpringAwareUserTransaction.java:412)
        at org.alfresco.repo.transaction.TransactionUtil.executeInTransaction(TransactionUtil.java:186)
        … 11 more
Caused by: java.lang.NullPointerException
        at org.alfresco.repo.domain.PropertyValue$ValueType.convert(PropertyValue.java:301)
        at org.alfresco.repo.domain.PropertyValue.getValue(PropertyValue.java:727)
        at org.alfresco.repo.node.AbstractNodeServiceImpl.makeSerializableValue(AbstractNodeServiceImpl.java:689)
        at org.alfresco.repo.node.db.DbNodeServiceImpl.getPropertiesImpl(DbNodeServiceImpl.java:860)
        at org.alfresco.repo.node.db.DbNodeServiceImpl.getProperties(DbNodeServiceImpl.java:845)
        at sun.reflect.GeneratedMethodAccessor161.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:585)
        at org.alfresco.repo.service.StoreRedirectorProxyFactory$RedirectorInvocationHandler.invoke(StoreRedirectorProxyFactory.java:221)
        at $Proxy2.getProperties(Unknown Source)
        at org.alfresco.repo.search.impl.lucene.LuceneIndexerImpl2.createDocuments(LuceneIndexerImpl2.java:1250)
        at org.alfresco.repo.search.impl.lucene.LuceneIndexerImpl2.indexImpl(LuceneIndexerImpl2.java:1170)
        at org.alfresco.repo.search.impl.lucene.LuceneIndexerImpl2.indexImpl(LuceneIndexerImpl2.java:1158)
        at org.alfresco.repo.search.impl.lucene.LuceneIndexerImpl2.flushPending(LuceneIndexerImpl2.java:961)
        at org.alfresco.repo.search.impl.lucene.LuceneIndexerImpl2.prepare(LuceneIndexerImpl2.java:732)
        at org.alfresco.repo.search.impl.lucene.LuceneIndexerAndSearcherFactory2.prepare(LuceneIndexerAndSearcherFactory2.java:706)
        at org.alfresco.repo.transaction.AlfrescoTransactionSupport$TransactionSynchronizationImpl.beforeCommit(AlfrescoTransactionSupport.java:599)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.triggerBeforeCommit(AbstractPlatformTransactionManager.java:657)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:482)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:469)
        at org.springframework.transaction.interceptor.TransactionAspectSupport.doCommitTransactionAfterReturning(TransactionAspectSupport.java:266)
        at org.alfresco.util.transaction.SpringAwareUserTransaction.commit(SpringAwareUserTransaction.java:405)
        … 12 more
07:34:01,818 ERROR [org.quartz.core.ErrorLogger] Job (DEFAULT.org.springframework.scheduling.quartz.JobDetailBean#17748d3 threw an exception.
org.quartz.SchedulerException: Job threw an unhandled exception. [See nested exception: org.alfresco.error.AlfrescoRuntimeException: Unexpected rollback of exception:
Transaction didn't commit: null]
        at org.quartz.core.JobRunShell.run(JobRunShell.java:213)
        at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:529)
Caused by: org.alfresco.error.AlfrescoRuntimeException: Unexpected rollback of exception:
Transaction didn't commit: null
        at org.alfresco.repo.transaction.TransactionUtil.executeInTransaction(TransactionUtil.java:192)
        at org.alfresco.repo.transaction.TransactionUtil.executeInNonPropagatingUserTransaction(TransactionUtil.java:134)
        at org.alfresco.repo.node.index.FullIndexRecoveryComponent.reindexTransaction(FullIndexRecoveryComponent.java:283)
        at org.alfresco.repo.node.index.FullIndexRecoveryComponent.performFullRecovery(FullIndexRecoveryComponent.java:206)
        at org.alfresco.repo.node.index.FullIndexRecoveryComponent.reindexImpl(FullIndexRecoveryComponent.java:165)
        at org.alfresco.repo.node.index.AbstractReindexComponent$1.doWork(AbstractReindexComponent.java:217)
        at org.alfresco.repo.transaction.TransactionUtil.executeInTransaction(TransactionUtil.java:176)
        at org.alfresco.repo.transaction.TransactionUtil.executeInUserTransaction(TransactionUtil.java:82)
        at org.alfresco.repo.node.index.AbstractReindexComponent.reindex(AbstractReindexComponent.java:221)
        at org.alfresco.repo.node.index.IndexRecoveryJob.execute(IndexRecoveryJob.java:31)
        at org.quartz.core.JobRunShell.run(JobRunShell.java:202)
        … 1 more
Caused by: javax.transaction.RollbackException: Transaction didn't commit: null
        at org.alfresco.util.transaction.SpringAwareUserTransaction.commit(SpringAwareUserTransaction.java:412)
        at org.alfresco.repo.transaction.TransactionUtil.executeInTransaction(TransactionUtil.java:186)
        … 11 more
Caused by: java.lang.NullPointerException
        at org.alfresco.repo.domain.PropertyValue$ValueType.convert(PropertyValue.java:301)
        at org.alfresco.repo.domain.PropertyValue.getValue(PropertyValue.java:727)
        at org.alfresco.repo.node.AbstractNodeServiceImpl.makeSerializableValue(AbstractNodeServiceImpl.java:689)
        at org.alfresco.repo.node.db.DbNodeServiceImpl.getPropertiesImpl(DbNodeServiceImpl.java:860)
        at org.alfresco.repo.node.db.DbNodeServiceImpl.getProperties(DbNodeServiceImpl.java:845)
        at sun.reflect.GeneratedMethodAccessor161.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:585)
        at org.alfresco.repo.service.StoreRedirectorProxyFactory$RedirectorInvocationHandler.invoke(StoreRedirectorProxyFactory.java:221)
        at $Proxy2.getProperties(Unknown Source)
        at org.alfresco.repo.search.impl.lucene.LuceneIndexerImpl2.createDocuments(LuceneIndexerImpl2.java:1250)
        at org.alfresco.repo.search.impl.lucene.LuceneIndexerImpl2.indexImpl(LuceneIndexerImpl2.java:1170)
        at org.alfresco.repo.search.impl.lucene.LuceneIndexerImpl2.indexImpl(LuceneIndexerImpl2.java:1158)
        at org.alfresco.repo.search.impl.lucene.LuceneIndexerImpl2.flushPending(LuceneIndexerImpl2.java:961)
        at org.alfresco.repo.search.impl.lucene.LuceneIndexerImpl2.prepare(LuceneIndexerImpl2.java:732)
        at org.alfresco.repo.search.impl.lucene.LuceneIndexerAndSearcherFactory2.prepare(LuceneIndexerAndSearcherFactory2.java:706)
        at org.alfresco.repo.transaction.AlfrescoTransactionSupport$TransactionSynchronizationImpl.beforeCommit(AlfrescoTransactionSupport.java:599)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.triggerBeforeCommit(AbstractPlatformTransactionManager.java:657)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:482)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:469)
        at org.springframework.transaction.interceptor.TransactionAspectSupport.doCommitTransactionAfterReturning(TransactionAspectSupport.java:266)
        at org.alfresco.util.transaction.SpringAwareUserTransaction.commit(SpringAwareUserTransaction.java:405)
        … 12 more

I have tried to upgrade a copy of Alfresco from 2.0C to 2.1C, but the problem persists. Alfresco 2.1 says "index recovery complete" but it hangs:


12:24:29,756 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    10 % complete.
12:25:15,443 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    20 % complete.
12:26:00,009 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    30 % complete.
12:26:47,899 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    40 % complete.
12:27:32,456 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    50 % complete.
12:28:19,574 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    60 % complete.
12:29:04,395 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    70 % complete.
12:29:57,765 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    80 % complete.
12:30:50,729 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    90 % complete.
12:31:42,665 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    100 % complete.
12:31:42,671 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent] Index recovery completed.
12:31:42,678 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:42,678 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 4.0E-4 ms
12:31:42,678 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:42,678 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for WRITE lock  - main
12:31:42,678 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT WRITE LOCK  - main -  in 2.0E-4 ms
12:31:42,678 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Start Index 90530289-4cab-11dc-8636-a9c756d18ff4 state = PREPARING
12:31:42,678 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=90530289-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=ACTIVE Docs=0 Deletions=0

12:31:42,678 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] End Index 90530289-4cab-11dc-8636-a9c756d18ff4 state = PREPARING
12:31:42,678 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=90530289-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=PREPARING Docs=0 Deletions=0

12:31:42,678 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:42,679 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 2.0E-4 ms
12:31:42,679 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED WRITE LOCK  - main
12:31:42,679 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:42,679 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:42,679 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 2.0E-4 ms
12:31:42,679 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:42,679 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for WRITE lock  - main
12:31:42,679 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT WRITE LOCK  - main -  in 2.0E-4 ms
12:31:42,679 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory] Replacing ref counting reader for MainReader
12:31:42,679 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory] Created ref counting reader for MainReader org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@10bd176
12:31:42,679 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:42,679 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 2.0E-4 ms
12:31:42,679 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED WRITE LOCK  - main
12:31:42,679 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@10bd176
12:31:42,679 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Main index reader references = 1
12:31:42,679 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:42,679 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:42,680 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 2.0E-4 ms
12:31:42,680 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:43,027 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:43,027 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 6.0E-4 ms
12:31:43,027 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:43,057 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] main: Reader MainReader closing        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@10bd176
12:31:43,057 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] main: Reader MainReader - decrement - ref count is 0        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@10bd176
12:31:43,057 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] main: Reader MainReader still open …. ref = 0 invalidForReuse = false        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@10bd176
12:31:43,094 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:43,094 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 5.0E-4 ms
12:31:43,094 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:43,094 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for WRITE lock  - main
12:31:43,094 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT WRITE LOCK  - main -  in 2.0E-4 ms
12:31:43,094 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Start Index 90530289-4cab-11dc-8636-a9c756d18ff4 state = ROLLINGBACK
12:31:43,094 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=90530289-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=PREPARING Docs=0 Deletions=0

12:31:43,150 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] End Index 90530289-4cab-11dc-8636-a9c756d18ff4 state = ROLLINGBACK
12:31:43,150 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=90530289-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=ROLLINGBACK Docs=0 Deletions=0

12:31:43,150 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:43,151 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 3.0E-4 ms
12:31:43,151 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED WRITE LOCK  - main
12:31:43,151 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:43,151 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:43,151 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 2.0E-4 ms
12:31:43,151 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:43,151 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for WRITE lock  - main
12:31:43,151 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT WRITE LOCK  - main -  in 2.0E-4 ms
12:31:43,151 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Start Index 90530289-4cab-11dc-8636-a9c756d18ff4 state = ROLLEDBACK
12:31:43,151 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=90530289-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=ROLLINGBACK Docs=0 Deletions=0

12:31:43,151 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] End Index 90530289-4cab-11dc-8636-a9c756d18ff4 state = ROLLEDBACK
12:31:43,152 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=90530289-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=ROLLEDBACK Docs=0 Deletions=0

12:31:43,152 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:43,152 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 4.0E-4 ms
12:31:43,152 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED WRITE LOCK  - main
12:31:43,152 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:43,152 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:43,152 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 2.0E-4 ms
12:31:43,152 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:43,152 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for WRITE lock  - main
12:31:43,152 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT WRITE LOCK  - main -  in 2.0E-4 ms
12:31:43,152 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Start Index 904287c6-4cab-11dc-8636-a9c756d18ff4 state = ROLLINGBACK
12:31:43,152 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=904287c6-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=ACTIVE Docs=0 Deletions=0

12:31:43,208 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] End Index 904287c6-4cab-11dc-8636-a9c756d18ff4 state = ROLLINGBACK
12:31:43,209 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=904287c6-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=ROLLINGBACK Docs=0 Deletions=0

12:31:43,209 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:43,209 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 4.0E-4 ms
12:31:43,209 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED WRITE LOCK  - main
12:31:43,209 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:43,209 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:43,209 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 2.0E-4 ms
12:31:43,209 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:43,209 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for WRITE lock  - main
12:31:43,209 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT WRITE LOCK  - main -  in 1.0E-4 ms
12:31:43,209 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Start Index 904287c6-4cab-11dc-8636-a9c756d18ff4 state = ROLLEDBACK
12:31:43,209 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=904287c6-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=ROLLINGBACK Docs=0 Deletions=0

12:31:43,210 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] End Index 904287c6-4cab-11dc-8636-a9c756d18ff4 state = ROLLEDBACK
12:31:43,210 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=904287c6-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=ROLLEDBACK Docs=0 Deletions=0

12:31:43,210 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:43,210 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 3.0E-4 ms
12:31:43,210 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED WRITE LOCK  - main
12:31:43,210 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:43,210 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:43,210 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 2.0E-4 ms
12:31:43,210 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:43,210 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for WRITE lock  - main
12:31:43,210 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT WRITE LOCK  - main -  in 2.0E-4 ms
12:31:43,210 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Start Index 90c3508a-4cab-11dc-8636-a9c756d18ff4 state = ROLLINGBACK
12:31:43,210 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=90c3508a-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=ACTIVE Docs=0 Deletions=0

12:31:43,283 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] End Index 90c3508a-4cab-11dc-8636-a9c756d18ff4 state = ROLLINGBACK
12:31:43,283 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=90c3508a-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=ROLLINGBACK Docs=0 Deletions=0

12:31:43,283 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:43,283 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 3.0E-4 ms
12:31:43,283 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED WRITE LOCK  - main
12:31:43,283 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:43,284 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:43,284 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 2.0E-4 ms
12:31:43,284 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:43,284 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for WRITE lock  - main
12:31:43,284 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT WRITE LOCK  - main -  in 2.0E-4 ms
12:31:43,284 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Start Index 90c3508a-4cab-11dc-8636-a9c756d18ff4 state = ROLLEDBACK
12:31:43,284 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=90c3508a-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=ROLLINGBACK Docs=0 Deletions=0

12:31:43,284 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] End Index 90c3508a-4cab-11dc-8636-a9c756d18ff4 state = ROLLEDBACK
12:31:43,284 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=90c3508a-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=ROLLEDBACK Docs=0 Deletions=0

12:31:43,284 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:43,285 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 3.0E-4 ms
12:31:43,285 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED WRITE LOCK  - main
12:31:43,285 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:43,285 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:43,285 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 2.0E-4 ms
12:31:43,285 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:43,285 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for WRITE lock  - main
12:31:43,285 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT WRITE LOCK  - main -  in 1.0E-4 ms
12:31:43,285 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Start Index 9048c957-4cab-11dc-8636-a9c756d18ff4 state = ROLLINGBACK
12:31:43,285 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=8ff18193-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=COMMITTED Docs=1 Deletions=1
2         Name=9048c957-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=ACTIVE Docs=0 Deletions=0

12:31:43,341 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] End Index 9048c957-4cab-11dc-8636-a9c756d18ff4 state = ROLLINGBACK
12:31:43,341 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=8ff18193-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=COMMITTED Docs=1 Deletions=1
2         Name=9048c957-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=ROLLINGBACK Docs=0 Deletions=0

12:31:43,341 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:43,341 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 4.0E-4 ms
12:31:43,341 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED WRITE LOCK  - main
12:31:43,342 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:43,342 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:43,342 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 2.0E-4 ms
12:31:43,342 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:43,342 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for WRITE lock  - main
12:31:43,342 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT WRITE LOCK  - main -  in 2.0E-4 ms
12:31:43,342 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Start Index 9048c957-4cab-11dc-8636-a9c756d18ff4 state = ROLLEDBACK
12:31:43,342 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=8ff18193-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=COMMITTED Docs=1 Deletions=1
2         Name=9048c957-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=ROLLINGBACK Docs=0 Deletions=0

12:31:43,342 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] End Index 9048c957-4cab-11dc-8636-a9c756d18ff4 state = ROLLEDBACK
12:31:43,342 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=8ff18193-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=COMMITTED Docs=1 Deletions=1
2         Name=9048c957-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=ROLLEDBACK Docs=0 Deletions=0

12:31:43,343 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:43,343 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 2.0E-4 ms
12:31:43,343 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED WRITE LOCK  - main
12:31:43,343 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:43,343 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:43,343 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 2.0E-4 ms
12:31:43,343 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:43,343 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for WRITE lock  - main
12:31:43,343 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT WRITE LOCK  - main -  in 2.0E-4 ms
12:31:43,343 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Start Index 904f0ae8-4cab-11dc-8636-a9c756d18ff4 state = ROLLINGBACK
12:31:43,343 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=904f0ae8-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=ACTIVE Docs=0 Deletions=0

12:31:43,408 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] End Index 904f0ae8-4cab-11dc-8636-a9c756d18ff4 state = ROLLINGBACK
12:31:43,408 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=904f0ae8-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=ROLLINGBACK Docs=0 Deletions=0

12:31:43,408 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:43,408 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 3.0E-4 ms
12:31:43,408 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED WRITE LOCK  - main
12:31:43,408 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:43,408 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:43,408 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 2.0E-4 ms
12:31:43,408 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:43,408 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for WRITE lock  - main
12:31:43,408 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT WRITE LOCK  - main -  in 2.0E-4 ms
12:31:43,408 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Start Index 904f0ae8-4cab-11dc-8636-a9c756d18ff4 state = ROLLEDBACK
12:31:43,408 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=904f0ae8-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=ROLLINGBACK Docs=0 Deletions=0

12:31:43,409 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] End Index 904f0ae8-4cab-11dc-8636-a9c756d18ff4 state = ROLLEDBACK
12:31:43,409 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=904f0ae8-4cab-11dc-8636-a9c756d18ff4 Type=DELTA Status=ROLLEDBACK Docs=0 Deletions=0

12:31:43,409 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - main
12:31:43,409 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - main -  in 3.0E-4 ms
12:31:43,409 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED WRITE LOCK  - main
12:31:43,409 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - main
12:31:43,411 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.node.index.AbstractReindexComponent.reindex(AbstractReindexComponent.java:220)
        at org.alfresco.repo.node.index.IndexRecoveryBootstrapBean.onBootstrap(IndexRecoveryBootstrapBean.java:35)
        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:3763)
        at org.apache.catalina.core.StandardContext.start(StandardContext.java:4211)
        at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:759)
        at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:739)
        at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:524)
        at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:809)
        at org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:698)
        at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:472)
        at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1122)
        at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:310)
        at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:119)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1021)
        at org.apache.catalina.core.StandardHost.start(StandardHost.java:718)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1013)
        at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:442)
        at org.apache.catalina.core.StandardService.start(StandardService.java:450)
        at org.apache.catalina.core.StandardServer.start(StandardServer.java:709)
        at org.apache.catalina.startup.Catalina.start(Catalina.java:551)
        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:585)
        at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:294)
        at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:432)
Caused by: javax.transaction.RollbackException: Transaction didn't commit: null
        at org.alfresco.util.transaction.SpringAwareUserTransaction.commit(SpringAwareUserTransaction.java:430)
        at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:239)
        … 37 more
Caused by: java.lang.NullPointerException
        at org.alfresco.repo.domain.PropertyValue$ValueType.convert(PropertyValue.java:331)
        at org.alfresco.repo.domain.PropertyValue.getValue(PropertyValue.java:771)
        at org.alfresco.repo.node.AbstractNodeServiceImpl.makeSerializableValue(AbstractNodeServiceImpl.java:689)
        at org.alfresco.repo.node.db.DbNodeServiceImpl.getPropertiesImpl(DbNodeServiceImpl.java:917)
        at org.alfresco.repo.node.db.DbNodeServiceImpl.getProperties(DbNodeServiceImpl.java:902)
        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:585)
        at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:281)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:187)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:154)
        at org.alfresco.repo.transaction.TransactionResourceInterceptor.invoke(TransactionResourceInterceptor.java:144)
        at org.springframework.aop…………. blahblahblah

Maybe deleting these "lost objets"…. but how to find them in the file system or database?
3 REPLIES 3

andy
Champ on-the-rise
Champ on-the-rise
Hi

From a quick look I do not see an obvious issue in the code.

Have you got custom models defined?
What database are you using?

ndy

miguel_gil_mart
Champ in-the-making
Champ in-the-making
Hi

From a quick look I do not see an obvious issue in the code.

Have you got custom models defined?
What database are you using?

ndy

Yes, I have custom models,… and they work very fine. I am using MySQL 5.
I resolved my problem recovering repository and migrating to 2.1. I wrote a PHP script to rebuild Alfresco folders to a 'real' folder tree. As you know, Alfresco uses /year/month/day/hour/min to save files. Well, I have written a PHP script to search in database and restore these files to 'space=directory' folder tree. I will upload this script to alfresco forge as soon as I can.
Thank you for your help.  Smiley Happy

vycitalr
Champ in-the-making
Champ in-the-making
Hello,
I have a similliar problem. I am running sort of user synchronization, where I create, delete, update and move users among groups. There are
few big groups that should contain a lot of users.  Simply said, I iterate through current alfresco users (I have them in hashtable,
there areabout 20000 of them) and process them one by one. This processing is mostly an update of its data (firstname, lastname etc) and moving from/to groups. This processing for one user is happening in one transaction. So typically there is one (or none) call to PersonService.setPersonProperties()
and several calls to AuthorityDAO.addAuthority(String parentName, String childName) and AuthorityService.removeAuthority(groupName, userName) in the transaction, and this is executed in a loop for all users.

This works fine from the begining, some users (eg hundreds) are processed and then I get the following exception:


2007-09-26 17:53:21,952 DEBUG [com.cleverbee.alfsynchro.backend.AlfrescoManager] updateGroupMemebershipForUser(, userName=bartajir
2007-09-26 17:53:21,953 DEBUG [com.cleverbee.alfsynchro.backend.AlfrescoManager] addMemberToGroup(), group=Z-ÄŚVUT <= bartajir
2007-09-26 17:53:22,646 DEBUG [com.cleverbee.alfsynchro.backend.AlfrescoManager] findActualMembershipForUser(, userName=holoutat
2007-09-26 17:53:22,660 DEBUG [com.cleverbee.alfsynchro.backend.AlfrescoManager] updateGroupMemebershipForUser(, userName=holoutat
2007-09-26 17:53:22,660 DEBUG [com.cleverbee.alfsynchro.backend.AlfrescoManager] addMemberToGroup(), group=Z-ÄŚVUT <= holoutat
2007-09-26 17:53:22,677 ERROR [com.cleverbee.alfsynchro.backend.AlfrescoManager] Unexepcted error:
java.lang.NullPointerException
   at org.alfresco.repo.domain.PropertyValue$ValueType.convert(PropertyValue.java:331)
   at org.alfresco.repo.domain.PropertyValue.getValue(PropertyValue.java:771)
   at org.alfresco.repo.node.AbstractNodeServiceImpl.makeSerializableValue(AbstractNodeServiceImpl.java:689)
   at org.alfresco.repo.node.db.DbNodeServiceImpl.getProperty(DbNodeServiceImpl.java:963)
   at sun.reflect.GeneratedMethodAccessor113.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at java.lang.reflect.Method.invoke(Method.java:585)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:281)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:187)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:154)
   at org.alfresco.repo.transaction.TransactionResourceInterceptor.invoke(TransactionResourceInterceptor.java:138)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:176)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:210)
   at $Proxy2.getProperty(Unknown Source)
   at sun.reflect.GeneratedMethodAccessor113.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at java.lang.reflect.Method.invoke(Method.java:585)
   at org.alfresco.repo.service.StoreRedirectorProxyFactory$RedirectorInvocationHandler.invoke(StoreRedirectorProxyFactory.java:221)
   at $Proxy3.getProperty(Unknown Source)
   at sun.reflect.GeneratedMethodAccessor113.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at java.lang.reflect.Method.invoke(Method.java:585)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:281)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:187)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:154)
   at org.alfresco.repo.node.MLPropertyInterceptor.invoke(MLPropertyInterceptor.java:145)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:176)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:210)
   at $Proxy2.getProperty(Unknown Source)
   at org.alfresco.repo.security.authority.AuthorityDAOImpl.addAuthority(AuthorityDAOImpl.java:121)
   at com.cleverbee.alfsynchro.backend.AlfrescoManager.addAuthority(AlfrescoManager.java:930)
   at com.cleverbee.alfsynchro.backend.AlfrescoManager.addMemberToGroup(AlfrescoManager.java:900)
   at com.cleverbee.alfsynchro.backend.AlfrescoManager.updateGroupMemebershipForUser(AlfrescoManager.java:621)
   at com.cleverbee.alfsynchro.backend.SynchManager.processActive(SynchManager.java:226)
   at com.cleverbee.alfsynchro.backend.SynchManager.refreshMembershipInternal(SynchManager.java:592)
   at com.cleverbee.alfsynchro.backend.SynchManager.refreshMembershipFull_new(SynchManager.java:422)
   at com.cleverbee.alfsynchro.backend.SynchManager.refreshMembershipFull(SynchManager.java:630)
   at com.cleverbee.alfsynchro.webclient.ClbAdminBean.mainAction(ClbAdminBean.java:91)
   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:585)
   at org.apache.myfaces.el.MethodBindingImpl.invoke(MethodBindingImpl.java:132)
   at javax.faces.component.UICommand.broadcast(UICommand.java:89)
   at javax.faces.component.UIViewRoot._broadcastForPhase(UIViewRoot.java:97)
   at javax.faces.component.UIViewRoot.processApplication(UIViewRoot.java:171)
   at org.apache.myfaces.lifecycle.InvokeApplicationExecutor.execute(InvokeApplicationExecutor.java:32)
   at org.apache.myfaces.lifecycle.LifecycleImpl.executePhase(LifecycleImpl.java:95)
   at org.apache.myfaces.lifecycle.LifecycleImpl.execute(LifecycleImpl.java:70)
   at javax.faces.webapp.FacesServlet.service(FacesServlet.java:139)
   at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:269)
   at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
   at org.alfresco.web.app.servlet.AdminAuthenticationFilter.doFilter(AdminAuthenticationFilter.java:80)
   at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)
   at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
   at org.alfresco.web.app.servlet.AuthenticationFilter.doFilter(AuthenticationFilter.java:81)
   at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)
   at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
   at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:210)
   at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:174)
   at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
   at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117)
   at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108)
   at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:151)
   at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:870)
   at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:665)
   at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:528)
   at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:81)
   at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:685)
   at java.lang.Thread.run(Thread.java:595)



After examination I found that corrupted data in DB, given by this query (which I think should return no data):

select * from alf_node_properties where
qname = '{http://www.alfresco.org/model/user/1.0}members'
and serializable_value is  null

But in my case it does return data. I suppose, if there exists the '{http://www.alfresco.org/model/user/1.0}members' for a node,
it is expected the serializable_value contains somewhat encoded members, but I have it here null.
When I looked at these appropriate groups via web-client I got the same error. Also  a FULL reinedex could NOT be run as well. If I deleted the results of the query above I could run the FULL reindex, see the groups (empty) and re-run my process. But after a while it got corrupted again.


Another issue is, this happened repeatedly on linux machine with oracle DB, but I did not meet with this on Windows (with oracle). But this might be only a coincidence.

From my point of view, I just call alfresco's api in a loop, and cannot find a problem in my code.
I am using Alfresco 2.1 Comunity Edition with Oracle database.

This is quite an urgent issue. Thank you very much for any comments.