Indexing does not work

[ashwini]

Hello There ,

After configuring index.recovery.mode=FULL , reindexing hangs for a while and continue again with below exception.

2012-09-04 04:27:23,416  ERROR [index.AbstractReindexComponent.threads] [indexTrackerThread1] Reindexer   206 failed with error: Possible cache integrity issue during reindexing; nested exception is org.alfresco.service.cmr.repository.InvalidNodeRefException: Node does not exist: workspace://SpacesStore/16a0b417-6aca-4e03-96d2-97debcf93357(Status[changeTxnId=927d124a-41ca-4b1e-9d9b-afd0067edd56, dbTxnId=230497, deleted=true]).
 2012-09-04 04:27:23,418  WARN  [index.AbstractReindexComponent.threads] [indexTrackerThread1] org.springframework.dao.ConcurrencyFailureException: Possible cache integrity issue during reindexing; nested exception is org.alfresco.service.cmr.repository.InvalidNodeRefException: Node does not exist: workspace://SpacesStore/16a0b417-6aca-4e03-96d2-97debcf93357(Status[changeTxnId=927d124a-41ca-4b1e-9d9b-afd0067edd56, dbTxnId=230497, deleted=true])
   at org.alfresco.repo.search.impl.lucene.AbstractLuceneIndexerImpl$1.execute(AbstractLuceneIndexerImpl.java:720)
   at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:388)
   at org.alfresco.repo.search.impl.lucene.AbstractLuceneIndexerImpl.doInReadthroughTransaction(AbstractLuceneIndexerImpl.java:702)
   at org.alfresco.repo.search.impl.lucene.ADMLuceneIndexerImpl.readDocuments(ADMLuceneIndexerImpl.java:1980)
   at org.alfresco.repo.search.impl.lucene.ADMLuceneIndexerImpl.flushPending(ADMLuceneIndexerImpl.java:1044)
   at org.alfresco.repo.search.impl.lucene.AbstractLuceneIndexerAndSearcherFactory.flush(AbstractLuceneIndexerAndSearcherFactory.java:874)
   at sun.reflect.GeneratedMethodAccessor337.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at java.lang.reflect.Method.invoke(Method.java:597)
   at org.alfresco.repo.service.StoreRedirectorProxyFactory$RedirectorInvocationHandler.invoke(StoreRedirectorProxyFactory.java:215)
   at $Proxy215.flush(Unknown Source)
   at org.alfresco.repo.search.IndexerComponent.flushPending(IndexerComponent.java:133)
   at org.alfresco.repo.node.index.AbstractReindexComponent$ReindexWorkerRunnable.beforeCommit(AbstractReindexComponent.java:1086)
   at org.alfresco.repo.transaction.AlfrescoTransactionSupport$TransactionSynchronizationImpl.doBeforeCommit(AlfrescoTransactionSupport.java:747)
   at org.alfresco.repo.transaction.AlfrescoTransactionSupport$TransactionSynchronizationImpl.doBeforeCommit(AlfrescoTransactionSupport.java:727)
   at org.alfresco.repo.transaction.AlfrescoTransactionSupport$TransactionSynchronizationImpl.beforeCommit(AlfrescoTransactionSupport.java:687)
   at org.springframework.transaction.support.TransactionSynchronizationUtils.triggerBeforeCommit(TransactionSynchronizationUtils.java:95)
   at org.springframework.transaction.support.AbstractPlatformTransactionManager.triggerBeforeCommit(AbstractPlatformTransactionManager.java:927)
   at org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:737)
   at org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:723)
   at org.springframework.transaction.interceptor.TransactionAspectSupport.commitTransactionAfterReturning(TransactionAspectSupport.java:393)
   at org.alfresco.util.transaction.SpringAwareUserTransaction.commit(SpringAwareUserTransaction.java:472)
   at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:410)
   at org.alfresco.repo.node.index.AbstractReindexComponent$ReindexWorkerRunnable.run(AbstractReindexComponent.java:1008)
   at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
   at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
   at java.lang.Thread.run(Thread.java:662)
Caused by: org.alfresco.service.cmr.repository.InvalidNodeRefException: Node does not exist: workspace://SpacesStore/16a0b417-6aca-4e03-96d2-97debcf93357(Status[changeTxnId=927d124a-41ca-4b1e-9d9b-afd0067edd56, dbTxnId=230497, deleted=true])
   at org.alfresco.repo.node.db.DbNodeServiceImpl.getNodePairNotNull(DbNodeServiceImpl.java:176)
   at org.alfresco.repo.node.db.DbNodeServiceImpl.getPaths(DbNodeServiceImpl.java:2174)
   at sun.reflect.GeneratedMethodAccessor309.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at java.lang.reflect.Method.invoke(Method.java:597)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:309)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
   at org.alfresco.repo.tenant.MultiTNodeServiceInterceptor.invoke(MultiTNodeServiceInterceptor.java:104)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
   at $Proxy8.getPaths(Unknown Source)
   at sun.reflect.GeneratedMethodAccessor309.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at java.lang.reflect.Method.invoke(Method.java:597)
   at org.alfresco.repo.service.StoreRedirectorProxyFactory$RedirectorInvocationHandler.invoke(StoreRedirectorProxyFactory.java:215)
   at $Proxy9.getPaths(Unknown Source)
   at org.alfresco.repo.search.impl.lucene.ADMLuceneIndexerImpl$3.doWork(ADMLuceneIndexerImpl.java:682)
   at org.alfresco.repo.search.impl.lucene.ADMLuceneIndexerImpl$3.doWork(ADMLuceneIndexerImpl.java:668)
   at org.alfresco.repo.security.authentication.AuthenticationUtil.runAs(AuthenticationUtil.java:519)
   at org.alfresco.repo.search.impl.lucene.ADMLuceneIndexerImpl.createDocumentsImpl(ADMLuceneIndexerImpl.java:667)
   at org.alfresco.repo.search.impl.lucene.ADMLuceneIndexerImpl.createDocuments(ADMLuceneIndexerImpl.java:633)
   at org.alfresco.repo.search.impl.lucene.ADMLuceneIndexerImpl$4.execute(ADMLuceneIndexerImpl.java:1985)
   at org.alfresco.repo.search.impl.lucene.ADMLuceneIndexerImpl$4.execute(ADMLuceneIndexerImpl.java:1981)
   at org.alfresco.repo.search.impl.lucene.AbstractLuceneIndexerImpl$1.execute(AbstractLuceneIndexerImpl.java:715)
   … 26 more‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

Has anybody faced above issue ?

I have read somewhere that indexing is background process then why I can not access alfresco till it is not done with indexing?

Thanks in advance!!
Ashwini

[amitabhandari1]

Hi Ashwini,

when you do any activity  on server like creation of content or modify metadata ,it indexes in background.There are option available to whether index in background or foreground.
. But when you are doing Full Indexing , you will have to  wait until and unless server gets Up.

Thanks

[ashwini]

Thanks Amita for clarification.

Is it possible for you to give input on mentioned issue also ?

Regards,
Ashwini

[amitabhandari1]

Hi Ashwini,

Can you please check on nodeBrowser  with this query
TX:927d124a-41ca-4b1e-9d9b-afd0067edd56

Also can , you fire query in database : select * from alf_node where uuid='16a0b417-6aca-4e03-96d2-97debcf93357'

Let me know  result.

Thanks,

[ashwini]

The result of  select * from alf_node where uuid='16a0b417-6aca-4e03-96d2-97debcf93357'  is as below:

id   version   store_id   uuid   transaction_id   node_deleted   type_qname_id   locale_id   acl_id   audit_creator   audit_created   audit_modifier   audit_modified   audit_accessed
266483   5   6   16a0b417-6aca-4e03-96d2-97debcf93357   230497   b'1'   633   5      Arsene_Woboyi   2012-05-18T14:37:04.560+02:00   Arsene_Woboyi   2012-05-18T14:37:39.102+02:00‍‍

   

Even I am getting some exception everyday around 21:00 , when scheduler runs and try to delete the contents

'>https://forums.alfresco.com/en/viewtopic.php?f=4&t=46214


So I tried to run below query
SELECT * FROM alf_child_assoc where parent_node_id = 266483
and the result is :

id   version   parent_node_id   type_qname_id   child_node_name_crc   child_node_name   child_node_id   qname_ns_id   qname_localname   qname_crc   is_primary   assoc_index
254898   1   266483   351   -493933189   e9e162fa-45c1-4f2f-92c8-52b450ae701b   266495   6   webpreview   1387062285   b'1'   -1‍‍

I am not getting whats failing here too . Might be this is the cause of exception which I am getting while full indexing.

And

Can you please check on nodeBrowser with this query
TX:927d124a-41ca-4b1e-9d9b-afd0067edd56

I tried searching with above query in share's nodeBrowser,  but I didnt get which select store I have to use. Could you give me some more input.

Regards,
Ashwini

[amitabhandari1]

Hi Ashwini,

Use workspace//spacestore and lucene query. Wh
while executing this query :
select * from alf_node where uuid='16a0b417-6aca-4e03-96d2-97debcf93357'

The value of node_deleted  column should be either t or f . Can you please confirm?

Thanks,

[ashwini]

Hi Amita ,

After executing select * from alf_node where uuid='16a0b417-6aca-4e03-96d2-97debcf93357'

I found node_deleted = b'1'

Node browser query displayed No items found message

Regards,
Ashwini

[amitabhandari1]

Hi Ashwini,

node_deleted column stores value of Boolean types. I am not sure why you are getting this value.
Are you facing this issue on your development server. if yes, you can try delete node from database.
You can look at 3 tables : alf_node , alf_node_properties and alf_node_aspects . And delete all rows associated with  this node.

Thanks,

[ashwini]

Thanks Amita.

I just googled for value b'1' and found

BOOL, BOOLEAN :These types are synonyms for TINYINT(1). A value of zero is considered false. Nonzero values are considered true.
TINYINT[(M)] [UNSIGNED] [ZEROFILL] :A very small integer. The signed range is -128 to 127. The unsigned range is 0 to 255.

in result I found : node_deleted = b'1' where  b'1' is nothing but TINYINT value.

it means node_deleted= true

based on id values which is in result of query 

select * from alf_node where uuid='16a0b417-6aca-4e03-96d2-97debcf93357'‍

tried to look into  alf_node_properties(node_id), alf_node_aspects (node_id) , which returned nothing.

[amitabhandari1]

Ok  , in that case  can u  try to delete this node from alf_node and check if you can run full indexing.