Lucene indexing after migration from jackrabbit to Oak.

[smg11 <sa...@gmail.com>]

We are using DocumentStore in backend MongoDB. As per the oak dcumentation
created Lucene indexing for JCR_DATA inside jcr:content node. For
incremental upload for fresh MongoDb contains query working absolutely fine. 

Problem is After migrating from Jackrabbit to OAk. After successful
migration created Lucene indexing. We have only 10 documents in Jackrabbit
sample. But indexing is running as asynchronously and continuously like the
stack trace attached below. Only 10 documents in Jackrabbit and asynchronous
indexing requested continuously from last one day.Request your help
regarding this.



Stack trace:

2016-02-08 14:20:19,102 INFO 
[org.apache.jackrabbit.oak.plugins.document.rdb.RDBDocumentStore] (default
task-11) RDBDocumentStore (1.0.26) instantiated for database Microsoft SQL
Server 10.50.1600 (10.50), using driver: Microsoft JDBC Driver 4.0 for SQL
Server 4.0.2206.100 (4.0), connecting to:
jdbc:sqlserver://192.168.4.194:1433;authenticationScheme=nativeAuthentication;xopenStates=false;sendTimeAsDatetime=true;trustServerCertificate=true;sendStringParametersAsUnicode=true;selectMethod=direct;responseBuffering=adaptive;packetSize=8000;multiSubnetFailover=false;loginTimeout=15;lockTimeout=-1;lastUpdateCount=true;encrypt=true;disableStatementPooling=true;databaseName=DMS79MIG;applicationName=Microsoft
JDBC Driver for SQL Server;applicationIntent=readwrite;, properties:
{collation_name=SQL_Latin1_General_CP1_CI_AS}, transaction isolation level:
TRANSACTION_READ_COMMITTED (2), .: ID varbinary(512), MODIFIED bigint,
HASBINARY smallint, DELETEDONCE smallint, MODCOUNT bigint, CMODCOUNT bigint,
DSIZE bigint, DATA nvarchar(4000), BDATA varbinary(2147483647) /*
{bigint=-5, nvarchar=-9, smallint=5, varbinary=-3} */ /* unique index
NODES.PK__NODES__3214EC2703317E3D on dbo.NODES (ID ASC) clustered */
2016-02-08 14:20:19,102 INFO 
[org.apache.jackrabbit.oak.plugins.document.rdb.RDBDocumentStore] (default
task-11) Tables present upon startup: [CLUSTERNODES, NODES, SETTINGS]
2016-02-08 14:20:19,102 INFO 
[org.apache.jackrabbit.oak.plugins.document.rdb.RDBDocumentStore] (default
task-11) Tables created upon startup: [JOURNAL]
2016-02-08 14:20:19,118 INFO 
[org.apache.jackrabbit.oak.plugins.document.rdb.RDBBlobStore] (default
task-11) Unsupported Microsoft SQL Server version: 10.50, expected at least
11.0
2016-02-08 14:20:19,118 INFO 
[org.apache.jackrabbit.oak.plugins.document.rdb.RDBBlobStore] (default
task-11) RDBBlobStore (1.0.26) instantiated for database Microsoft SQL
Server 10.50.1600 (10.50), using driver: Microsoft JDBC Driver 4.0 for SQL
Server 4.0.2206.100 (4.0), connecting to:
jdbc:sqlserver://192.168.4.194:1433;authenticationScheme=nativeAuthentication;xopenStates=false;sendTimeAsDatetime=true;trustServerCertificate=true;sendStringParametersAsUnicode=true;selectMethod=direct;responseBuffering=adaptive;packetSize=8000;multiSubnetFailover=false;loginTimeout=15;lockTimeout=-1;lastUpdateCount=true;encrypt=true;disableStatementPooling=true;databaseName=DMS79MIG;applicationName=Microsoft
JDBC Driver for SQL Server;applicationIntent=readwrite;, transaction
isolation level: TRANSACTION_READ_COMMITTED (2)
2016-02-08 14:20:19,118 INFO 
[org.apache.jackrabbit.oak.plugins.document.rdb.RDBBlobStore] (default
task-11) Tables present upon startup: [DATASTORE_DATA, DATASTORE_META]
2016-02-08 14:20:19,742 INFO 
[org.apache.jackrabbit.oak.plugins.document.DocumentNodeStore] (default
task-11) Initialized DocumentNodeStore with clusterNodeId: 4 (id: 4,
startTime: 1454921419679, machineId: mac:500b20524153, instanceId:
C:\wildfly-8.2.0\bin, pid: 8224, uuid: c3114920-4927-4ab1-add4-c6e311562d0c,
readWriteMode: null, state: NONE, revLock: NONE, oakVersion: 1.0.26)
2016-02-08 14:20:20,225 INFO 
[com.dharbor.dms.utils.CustomLuceneIndexProvider] (default task-11)
Repository created
2016-02-08 14:20:20,303 INFO 
[com.dharbor.dms.utils.CustomLuceneIndexProvider] (default task-11) lucene
Exists: null
2016-02-08 14:20:20,490 INFO 
[com.dharbor.dms.utils.CustomLuceneIndexProvider] (default task-11) Lucene
index created
2016-02-08 14:20:21,317 WARN 
[org.apache.jackrabbit.oak.spi.query.Cursors$TraversingCursor] (default
task-11) Traversed 1000 nodes with filter Filter(query=select [jcr:path],
[jcr:score], [rep:excerpt] from [nt:unstructured] as a where [PFS_RELNAME]
like 'DMSContent' and contains([jcr:content/*], 'Java') /* xpath:
//element(*,nt:unstructured)[ jcr:like(@PFS_RELNAME,'DMSContent') and
(jcr:contains(jcr:content , 'Java'))]/rep:excerpt(.) */
fullText=jcr:content/*:"Java", path=*, property=[rep:excerpt=[is not null],
PFS_RELNAME=[DMSContent]]); consider creating an index or changing the query
2016-02-08 14:20:22,175 WARN 
[org.apache.jackrabbit.oak.spi.query.Cursors$TraversingCursor] (default
task-11) Traversed 2000 nodes with filter Filter(query=select [jcr:path],
[jcr:score], [rep:excerpt] from [nt:unstructured] as a where [PFS_RELNAME]
like 'DMSContent' and contains([jcr:content/*], 'Java') /* xpath:
//element(*,nt:unstructured)[ jcr:like(@PFS_RELNAME,'DMSContent') and
(jcr:contains(jcr:content , 'Java'))]/rep:excerpt(.) */
fullText=jcr:content/*:"Java", path=*, property=[rep:excerpt=[is not null],
PFS_RELNAME=[DMSContent]]); consider creating an index or changing the query
2016-02-08 14:20:22,284 INFO 
[com.dharbor.dms.utils.CustomLuceneIndexProvider] (default task-11) All set
to go
2016-02-08 14:20:22,284 INFO 
[com.dharbor.dms.utils.CustomLuceneIndexProvider] (default task-11) Initla
test query execution started for lucene
2016-02-08 14:20:22,284 INFO 
[com.dharbor.dms.utils.CustomLuceneIndexProvider] (default task-11) Initial
test query execution over for lucene
2016-02-08 14:20:25,124 INFO 
[org.apache.jackrabbit.oak.plugins.index.AsyncIndexUpdate]
(oak-scheduled-executor-3) Initial async index update
2016-02-08 14:20:25,139 INFO 
[org.apache.jackrabbit.oak.plugins.index.IndexUpdate]
(oak-scheduled-executor-3) Found a new index node [counter]. Reindexing
would be requested
2016-02-08 14:20:25,155 WARN 
[org.apache.jackrabbit.oak.plugins.index.AsyncIndexUpdate]
(oak-scheduled-executor-3) [async] The index update failed:
org.apache.jackrabbit.oak.api.CommitFailedException: OakAsync0002: Missing
index provider detected for type [counter] on index [/oak:index/counter]
	at
org.apache.jackrabbit.oak.plugins.index.AsyncIndexUpdate$DefaultMissingIndexProviderStrategy.onMissingIndex(AsyncIndexUpdate.java:836)
[oak-core-1.0.26.jar:1.0.26]
	at
org.apache.jackrabbit.oak.plugins.index.IndexUpdate.collectIndexEditors(IndexUpdate.java:202)
[oak-core-1.0.26.jar:1.0.26]
	at
org.apache.jackrabbit.oak.plugins.index.IndexUpdate.enter(IndexUpdate.java:144)
[oak-core-1.0.26.jar:1.0.26]
	at
org.apache.jackrabbit.oak.spi.commit.VisibleEditor.enter(VisibleEditor.java:57)
[oak-core-1.0.26.jar:1.0.26]
	at
org.apache.jackrabbit.oak.spi.commit.EditorDiff.process(EditorDiff.java:49)
[oak-core-1.0.26.jar:1.0.26]
	at
org.apache.jackrabbit.oak.plugins.index.AsyncIndexUpdate.updateIndex(AsyncIndexUpdate.java:372)
[oak-core-1.0.26.jar:1.0.26]
	at
org.apache.jackrabbit.oak.plugins.index.AsyncIndexUpdate.run(AsyncIndexUpdate.java:322)
[oak-core-1.0.26.jar:1.0.26]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[rt.jar:1.8.0_72]
	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
[rt.jar:1.8.0_72]
	at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
[rt.jar:1.8.0_72]
	at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
[rt.jar:1.8.0_72]
	at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[rt.jar:1.8.0_72]
	at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[rt.jar:1.8.0_72]
	at java.lang.Thread.run(Thread.java:745) [rt.jar:1.8.0_72]

2016-02-08 14:20:30,180 INFO 
[org.apache.jackrabbit.oak.plugins.index.AsyncIndexUpdate]
(oak-scheduled-executor-7) Initial async index update
2016-02-08 14:20:30,227 INFO 
[org.apache.jackrabbit.oak.plugins.index.IndexUpdate]
(oak-scheduled-executor-7) Found a new index node [counter]. Reindexing
would be requested
2016-02-08 14:20:35,286 INFO 
[org.apache.jackrabbit.oak.plugins.index.AsyncIndexUpdate]
(oak-scheduled-executor-9) Initial async index update
2016-02-08 14:20:35,302 INFO 
[org.apache.jackrabbit.oak.plugins.index.IndexUpdate]
(oak-scheduled-executor-9) Found a new index node [counter]. Reindexing
would be requested
2016-02-08 14:20:40,328 INFO 
[org.apache.jackrabbit.oak.plugins.index.AsyncIndexUpdate]
(oak-scheduled-executor-13) Initial async index update
2016-02-08 14:20:40,375 INFO 
[org.apache.jackrabbit.oak.plugins.index.IndexUpdate]
(oak-scheduled-executor-13) Found a new index node [counter]. Reindexing
would be requested
2016-02-08 14:20:45,445 INFO 
[org.apache.jackrabbit.oak.plugins.index.AsyncIndexUpdate]
(oak-scheduled-executor-9) Initial async index update
2016-02-08 14:20:45,492 INFO 
[org.apache.jackrabbit.oak.plugins.index.IndexUpdate]
(oak-scheduled-executor-9) Found a new index node [counter]. Reindexing
would be requested
2016-02-08 14:20:50,562 INFO 
[org.apache.jackrabbit.oak.plugins.index.AsyncIndexUpdate]
(oak-scheduled-executor-22) Initial async index update
2016-02-08 14:20:50,608 INFO 
[org.apache.jackrabbit.oak.plugins.index.IndexUpdate]
(oak-scheduled-executor-22) Found a new index node [counter]. Reindexing
would be requested
2016-02-08 14:20:55,672 INFO 
[org.apache.jackrabbit.oak.plugins.index.AsyncIndexUpdate]
(oak-scheduled-executor-6) Initial async index update
2016-02-08 14:20:55,672 INFO 
[org.apache.jackrabbit.oak.plugins.index.IndexUpdate]
(oak-scheduled-executor-6) Found a new index node [counter]. Reindexing
would be requested
2016-02-08 14:21:00,698 INFO 
[org.apache.jackrabbit.oak.plugins.index.AsyncIndexUpdate]
(oak-scheduled-executor-0) Initial async index update
2016-02-08 14:21:00,698 INFO 
[org.apache.jackrabbit.oak.plugins.index.IndexUpdate]
(oak-scheduled-executor-0) Found a new index node [counter]. Reindexing
would be requested
2016-02-08 14:21:05,721 INFO 
[org.apache.jackrabbit.oak.plugins.index.AsyncIndexUpdate]
(oak-scheduled-executor-20) Initial async index update
2016-02-08 14:21:05,737 INFO 
[org.apache.jackrabbit.oak.plugins.index.IndexUpdate]
(oak-scheduled-executor-20) Found a new index node [counter]. Reindexing
would be requested
2016-02-08 14:21:10,763 INFO 
[org.apache.jackrabbit.oak.plugins.index.AsyncIndexUpdate]
(oak-scheduled-executor-25) Initial async index update
2016-02-08 14:21:10,763 INFO 
[org.apache.jackrabbit.oak.plugins.index.IndexUpdate]
(oak-scheduled-executor-25) Found a new index node [counter]. Reindexing
would be requested
2016-02-08 14:21:15,783 INFO 
[org.apache.jackrabbit.oak.plugins.index.AsyncIndexUpdate]
(oak-scheduled-executor-14) Initial async index update
2016-02-08 14:21:15,801 INFO 
[org.apache.jackrabbit.oak.plugins.index.IndexUpdate]
(oak-scheduled-executor-14) Found a new index node [counter]. Reindexing
would be requested
2016-02-08 14:21:20,846 INFO 
[org.apache.jackrabbit.oak.plugins.index.AsyncIndexUpdate]
(oak-scheduled-executor-17) Initial async index update
2016-02-08 14:21:20,846 INFO 
[org.apache.jackrabbit.oak.plugins.index.IndexUpdate]
(oak-scheduled-executor-17) Found a new index node [counter]. Reindexing
would be requested
2016-02-08 14:21:25,869 INFO 
[org.apache.jackrabbit.oak.plugins.index.AsyncIndexUpdate]
(oak-scheduled-executor-5) Initial async index update
2016-02-08 14:21:25,885 INFO 
[org.apache.jackrabbit.oak.plugins.index.IndexUpdate]
(oak-scheduled-executor-5) Found a new index node [counter]. Reindexing
would be requested
2016-02-08 14:21:30,903 INFO 
[org.apache.jackrabbit.oak.plugins.index.AsyncIndexUpdate]
(oak-scheduled-executor-13) Initial async index update
2016-02-08 14:21:30,919 INFO 
[org.apache.jackrabbit.oak.plugins.index.IndexUpdate]
(oak-scheduled-executor-13) Found a new index node [counter]. Reindexing
would be requested
2016-02-08 14:21:35,932 INFO 
[org.apache.jackrabbit.oak.plugins.index.AsyncIndexUpdate]
(oak-scheduled-executor-30) Initial async index update
2016-02-08 14:21:35,947 INFO 
[org.apache.jackrabbit.oak.plugins.index.IndexUpdate]
(oak-scheduled-executor-30) Found a new index node [counter]. Reindexing
would be requested
2016-02-08 14:21:40,973 INFO 
[org.apache.jackrabbit.oak.plugins.index.AsyncIndexUpdate]
(oak-scheduled-executor-24) Initial async index update
2016-02-08 14:21:40,973 INFO 
[org.apache.jackrabbit.oak.plugins.index.IndexUpdate]
(oak-scheduled-executor-24) Found a new index node [counter]. Reindexing
would be requested
2016-02-08 14:21:45,997 INFO 
[org.apache.jackrabbit.oak.plugins.index.AsyncIndexUpdate]
(oak-scheduled-executor-25) Initial async index update
2016-02-08 14:21:46,012 INFO 
[org.apache.jackrabbit.oak.plugins.index.IndexUpdate]
(oak-scheduled-executor-25) Found a new index node [counter]. Reindexing
would be requested
2016-02-08 14:21:51,035 INFO 
[org.apache.jackrabbit.oak.plugins.index.AsyncIndexUpdate]
(oak-scheduled-executor-28) Initial async index update 




--
View this message in context: http://jackrabbit.510166.n4.nabble.com/Lucene-indexing-after-migration-from-jackrabbit-to-Oak-tp4663626.html
Sent from the Jackrabbit - Users mailing list archive at Nabble.com.