[
https://issues.apache.org/jira/browse/OAK-4214?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
Vikas Saurabh resolved OAK-4214.
--------------------------------
Resolution: Fixed
Fix Version/s: 1.5.2
Used ExecutorCloser in [r1739721|https://svn.apache.org/r1739721] on trunk.
> Many tests of LucenePropertyIndexTest leave temp files undleted
> ---------------------------------------------------------------
>
> Key: OAK-4214
> URL: https://issues.apache.org/jira/browse/OAK-4214
> Project: Jackrabbit Oak
> Issue Type: Technical task
> Components: lucene
> Reporter: Vikas Saurabh
> Assignee: Vikas Saurabh
> Fix For: 1.5.2
>
>
> The issue seems to happen only when running the whole test class. Running
> individual tests seem to clean up nicely.
> Using a custom TempFolder implementation, it seems that the issue is while
> deletion is going on, IndexCopier kicks in and writes in more files... thus
> avoid removal of non-empty folder.
> Here's a snip of what a patched cleanup looked like:
> {noformat}
> 19:01:30.686 INFO [main] MyTemporaryFolder.java:64 before delete
> LucenePropertyIndexTest_aggregateAndIncludeRelativePropertyByDefault
> 19:01:30.697 DEBUG [pool-25-thread-2] PerfLogger.java:179
> [/oak:index/test1] Copied file _0.si of size 257 B [took 31ms]
> 19:01:30.697 INFO [pool-25-thread-2] IndexCopier.java:426
> [java.lang.Thread.getStackTrace(Thread.java:1567),
> org.apache.jackrabbit.oak.plugins.index.lucene.IndexCopier$CopyOnReadDirectory.copyFilesToLocal(IndexCopier.java:426),
>
> org.apache.jackrabbit.oak.plugins.index.lucene.IndexCopier$CopyOnReadDirectory.access$600(IndexCopier.java:300),
>
> org.apache.jackrabbit.oak.plugins.index.lucene.IndexCopier$CopyOnReadDirectory$1.run(IndexCopier.java:397),
>
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145),
>
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615),
> java.lang.Thread.run(Thread.java:722)]
> 19:01:30.699 INFO [main] MyTemporaryFolder.java:66 after delete
> LucenePropertyIndexTest_aggregateAndIncludeRelativePropertyByDefault...
> folder exists: true
> {noformat}
> {{before delete}} and {{after delete}} logs from custom temp folder rule are
> logged before and fater folder.delete() is called (log snippet for that test
> at \[0])
> [~chetanm], I'm not sure of the internals of IndexCopier or how it utilizes
> ExecutorService...but it seems to me that we should have some {{@After}}
> method to shutdown index copying threads.
> \[0]:
> {noformat}
> 19:01:30.402 INFO [main] MyTemporaryFolder.java:57 before create
> LucenePropertyIndexTest_aggregateAndIncludeRelativePropertyByDefault
> 19:01:30.405 INFO [main] MyTemporaryFolder.java:59 after create
> LucenePropertyIndexTest_aggregateAndIncludeRelativePropertyByDefault
> 19:01:30.431 INFO [main] IndexUpdate.java:183 Found a new index
> node [reference]. Reindexing is requested
> 19:01:30.431 WARN [main] IndexUpdate.java:385 Missing index
> provider of type [reference], requesting reindex on [/oak:index/reference]
> 19:01:30.431 INFO [main] IndexUpdate.java:148 Reindexing will
> be performed for following indexes: [/oak:index/nodetype, /oak:index/uuid]
> 19:01:30.442 INFO [main] IndexUpdate.java:266 Indexing report
> - /oak:index/nodetype*(1210)
> 19:01:30.447 DEBUG [main] IndexUpdate.java:268 Indexing report
> - /oak:index/nodetype(4)
> 19:01:30.455 INFO [main] IndexUpdate.java:148 Reindexing will
> be performed for following indexes: [/oak:index/test1]
> 19:01:30.460 DEBUG [main] IndexCopier.java:178 IndexWriter would
> use
> C:\Users\vsaurabh\Documents\Projects\CQ-misc\jackrabbit-oak\oak-lucene\target\tmp2\junitLucenePropertyIndexTest_aggregateAndIncludeRelativePropertyByDefault264033588294762026\indexWriterDir\49
> 19:01:30.460 DEBUG [main] IndexCopier.java:182 IndexPath
> [indexPath] not configured in index definition Lucene Index : <No 'name'
> property defined>. Writer would create index files in temporary dir
> C:\Users\vsaurabh\Documents\Projects\CQ-misc\jackrabbit-oak\oak-lucene\target\tmp2\junitLucenePropertyIndexTest_aggregateAndIncludeRelativePropertyByDefault264033588294762026\indexWriterDir\49
> which would be deleted upon close. For better performance do configure the
> 'indexPath' as part of your index definition
> 19:01:30.461 DEBUG [main] PerfLogger.java:179 Created
> IndexWriter for directory Lucene Index : <No 'name' property defined> [took
> 2ms]
> 19:01:30.461 DEBUG [main] IndexCopier.java:979 [COW][UNKNOWN]
> Creating output segments_1
> 19:01:30.468 DEBUG [main] IndexCopier.java:979 [COW][UNKNOWN]
> Creating output segments.gen
> 19:01:30.468 DEBUG [pool-25-thread-1] PerfLogger.java:179 [COW][UNKNOWN]
> Copied to remote segments_1 -- size: 45 B [took 4ms]
> 19:01:30.476 DEBUG [main] PerfLogger.java:179 Closed writer for
> directory Lucene Index : <No 'name' property defined> [took 15ms]
> 19:01:30.477 DEBUG [pool-25-thread-1] PerfLogger.java:179 [COW][UNKNOWN]
> Copied to remote segments.gen -- size: 20 B [took 4ms]
> 19:01:30.477 DEBUG [main] PerfLogger.java:179 [COW][UNKNOWN]
> Completed pending copying task 0 [took 0ms]
> 19:01:30.477 INFO [main] IndexCopier.java:781 [COW][UNKNOWN]
> CopyOnWrite stats : Skipped copying 0 files with total size 0 B
> 19:01:30.482 DEBUG [main] IndexCopier.java:1129 Removed old index
> content from
> C:\Users\vsaurabh\Documents\Projects\CQ-misc\jackrabbit-oak\oak-lucene\target\tmp2\junitLucenePropertyIndexTest_aggregateAndIncludeRelativePropertyByDefault264033588294762026\indexWriterDir\49
>
> 19:01:30.483 DEBUG [main] PerfLogger.java:179 Closed directory
> for directory Lucene Index : <No 'name' property defined> [took 21ms]
> 19:01:30.483 DEBUG [main] PerfLogger.java:179 Overall Closed
> IndexWriter for directory Lucene Index : <No 'name' property defined> [took
> 22ms]
> 19:01:30.483 DEBUG [main] LuceneIndexEditorContext.java:500 Text extraction
> stats 0 (Time Taken 0 min, 0 sec, Bytes Read 0 B, Extracted text size 0 B)
> 19:01:30.484 INFO [main] IndexUpdate.java:266 Indexing report
> - /oak:index/nodetype(1)
> 19:01:30.496 DEBUG [main] IndexCopier.java:178 IndexWriter would
> use
> C:\Users\vsaurabh\Documents\Projects\CQ-misc\jackrabbit-oak\oak-lucene\target\tmp2\junitLucenePropertyIndexTest_aggregateAndIncludeRelativePropertyByDefault264033588294762026\indexWriterDir\50
> 19:01:30.497 DEBUG [main] IndexCopier.java:182 IndexPath
> [indexPath] not configured in index definition Lucene Index : <No 'name'
> property defined>. Writer would create index files in temporary dir
> C:\Users\vsaurabh\Documents\Projects\CQ-misc\jackrabbit-oak\oak-lucene\target\tmp2\junitLucenePropertyIndexTest_aggregateAndIncludeRelativePropertyByDefault264033588294762026\indexWriterDir\50
> which would be deleted upon close. For better performance do configure the
> 'indexPath' as part of your index definition
> 19:01:30.499 DEBUG [main] PerfLogger.java:179 Created
> IndexWriter for directory Lucene Index : <No 'name' property defined> [took
> 4ms]
> 19:01:30.500 DEBUG [main] IndexCopier.java:979 [COW][UNKNOWN]
> Creating output _0.fdt
> 19:01:30.502 DEBUG [main] IndexCopier.java:979 [COW][UNKNOWN]
> Creating output _0.fdx
> 19:01:30.507 DEBUG [main] IndexCopier.java:979 [COW][UNKNOWN]
> Creating output _0.doc
> 19:01:30.509 DEBUG [main] IndexCopier.java:979 [COW][UNKNOWN]
> Creating output _0.pos
> 19:01:30.512 DEBUG [main] IndexCopier.java:979 [COW][UNKNOWN]
> Creating output _0.tim
> 19:01:30.513 DEBUG [pool-25-thread-2] PerfLogger.java:179 [COW][UNKNOWN]
> Copied to remote _0.fdt -- size: 59 B [took 6ms]
> 19:01:30.514 DEBUG [main] IndexCopier.java:979 [COW][UNKNOWN]
> Creating output _0.tip
> 19:01:30.519 DEBUG [pool-25-thread-2] PerfLogger.java:179 [COW][UNKNOWN]
> Copied to remote _0.fdx -- size: 42 B [took 5ms]
> 19:01:30.527 DEBUG [pool-25-thread-1] PerfLogger.java:179 [COW][UNKNOWN]
> Copied to remote _0.tim -- size: 209 B [took 6ms]
> 19:01:30.527 DEBUG [main] IndexCopier.java:979 [COW][UNKNOWN]
> Creating output _0.nvd
> 19:01:30.530 DEBUG [main] IndexCopier.java:979 [COW][UNKNOWN]
> Creating output _0.nvm
> 19:01:30.536 DEBUG [pool-25-thread-1] PerfLogger.java:179 [COW][UNKNOWN]
> Copied to remote _0.tip -- size: 117 B [took 8ms]
> 19:01:30.539 DEBUG [main] IndexCopier.java:979 [COW][UNKNOWN]
> Creating output _0.fnm
> 19:01:30.544 DEBUG [pool-25-thread-1] PerfLogger.java:179 [COW][UNKNOWN]
> Copied to remote _0.doc -- size: 67 B [took 7ms]
> 19:01:30.547 DEBUG [main] IndexCopier.java:979 [COW][UNKNOWN]
> Creating output _0.cfs
> 19:01:30.550 DEBUG [pool-25-thread-1] PerfLogger.java:179 [COW][UNKNOWN]
> Copied to remote _0.pos -- size: 42 B [took 5ms]
> 19:01:30.557 DEBUG [pool-25-thread-1] PerfLogger.java:179 [COW][UNKNOWN]
> Copied to remote _0.nvd -- size: 27 B [took 6ms]
> 19:01:30.563 DEBUG [pool-25-thread-1] PerfLogger.java:179 [COW][UNKNOWN]
> Copied to remote _0.nvm -- size: 46 B [took 6ms]
> 19:01:30.571 DEBUG [main] IndexCopier.java:979 [COW][UNKNOWN]
> Creating output _0.cfe
> 19:01:30.579 DEBUG [main] IndexCopier.java:979 [COW][UNKNOWN]
> Creating output _0.si
> 19:01:30.581 DEBUG [pool-25-thread-2] PerfLogger.java:179 [COW][UNKNOWN]
> Copied to remote _0.cfs -- size: 755 B [took 9ms]
> 19:01:30.586 DEBUG [main] IndexCopier.java:979 [COW][UNKNOWN]
> Creating output segments_2
> 19:01:30.588 DEBUG [pool-25-thread-2] PerfLogger.java:179 [COW][UNKNOWN]
> Copied to remote _0.cfe -- size: 224 B [took 6ms]
> 19:01:30.596 DEBUG [pool-25-thread-2] PerfLogger.java:179 [COW][UNKNOWN]
> Copied to remote _0.si -- size: 257 B [took 6ms]
> 19:01:30.603 DEBUG [main] IndexCopier.java:979 [COW][UNKNOWN]
> Creating output segments.gen
> 19:01:30.603 DEBUG [pool-25-thread-2] PerfLogger.java:179 [COW][UNKNOWN]
> Copied to remote segments_2 -- size: 81 B [took 5ms]
> 19:01:30.613 DEBUG [main] PerfLogger.java:179 Closed writer for
> directory Lucene Index : <No 'name' property defined> [took 109ms]
> 19:01:30.613 DEBUG [pool-25-thread-2] PerfLogger.java:179 [COW][UNKNOWN]
> Copied to remote segments.gen -- size: 20 B [took 7ms]
> 19:01:30.614 DEBUG [main] PerfLogger.java:179 [COW][UNKNOWN]
> Completed pending copying task 1 [took 0ms]
> 19:01:30.632 DEBUG [main] IndexCopier.java:783 [COW][UNKNOWN]
> CopyOnWrite stats : Skipped copying 0 files with total size 0 B
> 19:01:30.647 DEBUG [main] IndexCopier.java:1129 Removed old index
> content from
> C:\Users\vsaurabh\Documents\Projects\CQ-misc\jackrabbit-oak\oak-lucene\target\tmp2\junitLucenePropertyIndexTest_aggregateAndIncludeRelativePropertyByDefault264033588294762026\indexWriterDir\50
>
> 19:01:30.648 DEBUG [main] PerfLogger.java:179 Closed directory
> for directory Lucene Index : <No 'name' property defined> [took 144ms]
> 19:01:30.648 DEBUG [main] PerfLogger.java:179 Overall Closed
> IndexWriter for directory Lucene Index : <No 'name' property defined> [took
> 144ms]
> 19:01:30.648 DEBUG [main] LuceneIndexEditorContext.java:500 Text extraction
> stats 1 (Time Taken 0 min, 0 sec, Bytes Read 5 B, Extracted text size 12 B)
> 19:01:30.649 DEBUG [main] LuceneIndexEditor.java:216 [<No 'name'
> property defined>] => Indexed 1 nodes, done.
> 19:01:30.649 DEBUG [main] IndexUpdate.java:268 Indexing report
> - /oak:index/test1(1)
> - /oak:index/nodetype(2)
> 19:01:30.650 DEBUG [main] QueryEngineImpl.java:154 Parsing JCR-SQL2
> statement: select [jcr:path] from [oak:TestNode] where contains(*, 'hello')
> 19:01:30.650 DEBUG [main] QueryImpl.java:1066 cost using filter
> Filter(query=select [jcr:path] from [oak:TestNode] where contains(*, 'hello')
> fullText="hello", path=*)
> 19:01:30.651 DEBUG [main] QueryImpl.java:1066 cost for nodeType
> is Infinity
> 19:01:30.658 INFO [pool-25-thread-1] IndexCopier.java:426
> [java.lang.Thread.getStackTrace(Thread.java:1567),
> org.apache.jackrabbit.oak.plugins.index.lucene.IndexCopier$CopyOnReadDirectory.copyFilesToLocal(IndexCopier.java:426),
>
> org.apache.jackrabbit.oak.plugins.index.lucene.IndexCopier$CopyOnReadDirectory.access$600(IndexCopier.java:300),
>
> org.apache.jackrabbit.oak.plugins.index.lucene.IndexCopier$CopyOnReadDirectory$1.run(IndexCopier.java:397),
>
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145),
>
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615),
> java.lang.Thread.run(Thread.java:722)]
> 19:01:30.662 DEBUG [main] IndexPlanner.java:486 Applicable
> IndexingRule found IndexRule: oak:TestNode
> 19:01:30.663 DEBUG [main] QueryImpl.java:1066 cost for
> lucene-property[/oak:index/test1] is 2.0
> 19:01:30.663 DEBUG [main] QueryEngineImpl.java:299 No alternatives
> found. Query: select [oak:TestNode].[jcr:path] as [jcr:path] from
> [oak:TestNode] as [oak:TestNode] where contains([oak:TestNode].[*], 'hello')
> 19:01:30.663 DEBUG [main] QueryImpl.java:1066 query execute
> select [jcr:path] from [oak:TestNode] where contains(*, 'hello')
> 19:01:30.664 DEBUG [main] QueryImpl.java:1066 query plan
> [oak:TestNode] as [oak:TestNode] /* lucene:test1(/oak:index/test1)
> :fulltext:hello ft:("hello") where contains([oak:TestNode].[*], 'hello') */
> 19:01:30.665 DEBUG [main] LucenePropertyIndex.java:404 loading the first
> 50 entries for query :fulltext:hello
> 19:01:30.663 INFO [pool-25-thread-2] IndexCopier.java:426
> [java.lang.Thread.getStackTrace(Thread.java:1567),
> org.apache.jackrabbit.oak.plugins.index.lucene.IndexCopier$CopyOnReadDirectory.copyFilesToLocal(IndexCopier.java:426),
>
> org.apache.jackrabbit.oak.plugins.index.lucene.IndexCopier$CopyOnReadDirectory.access$600(IndexCopier.java:300),
>
> org.apache.jackrabbit.oak.plugins.index.lucene.IndexCopier$CopyOnReadDirectory$1.run(IndexCopier.java:397),
>
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145),
>
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615),
> java.lang.Thread.run(Thread.java:722)]
> 19:01:30.665 DEBUG [main] PerfLogger.java:179 1 ... [took 0ms]
> 19:01:30.666 DEBUG [main] PerfLogger.java:179 facets retrieved
> [took 0ms]
> 19:01:30.666 DEBUG [main] LucenePropertyIndex.java:397 loading the next
> 100 entries for query :fulltext:hello
> 19:01:30.667 DEBUG [main] PerfLogger.java:179 0 ... [took 0ms]
> 19:01:30.667 DEBUG [main] PerfLogger.java:179 facets retrieved
> [took 0ms]
> 19:01:30.668 DEBUG [main] QueryEngineImpl.java:154 Parsing JCR-SQL2
> statement: select [jcr:path] from [oak:TestNode] where contains(*, 'image')
> 19:01:30.668 DEBUG [main] QueryImpl.java:1066 cost using filter
> Filter(query=select [jcr:path] from [oak:TestNode] where contains(*, 'image')
> fullText="image", path=*)
> 19:01:30.669 DEBUG [main] QueryImpl.java:1066 cost for nodeType
> is Infinity
> 19:01:30.669 DEBUG [main] IndexPlanner.java:486 Applicable
> IndexingRule found IndexRule: oak:TestNode
> 19:01:30.669 DEBUG [main] QueryImpl.java:1066 cost for
> lucene-property[/oak:index/test1] is 2.0
> 19:01:30.669 DEBUG [main] QueryEngineImpl.java:299 No alternatives
> found. Query: select [oak:TestNode].[jcr:path] as [jcr:path] from
> [oak:TestNode] as [oak:TestNode] where contains([oak:TestNode].[*], 'image')
> 19:01:30.670 DEBUG [main] QueryImpl.java:1066 query execute
> select [jcr:path] from [oak:TestNode] where contains(*, 'image')
> 19:01:30.671 DEBUG [main] QueryImpl.java:1066 query plan
> [oak:TestNode] as [oak:TestNode] /* lucene:test1(/oak:index/test1)
> :fulltext:image ft:("image") where contains([oak:TestNode].[*], 'image') */
> 19:01:30.672 DEBUG [main] LucenePropertyIndex.java:404 loading the first
> 50 entries for query :fulltext:image
> 19:01:30.673 DEBUG [main] PerfLogger.java:179 1 ... [took 0ms]
> 19:01:30.673 DEBUG [main] PerfLogger.java:179 facets retrieved
> [took 0ms]
> 19:01:30.674 DEBUG [main] LucenePropertyIndex.java:397 loading the next
> 100 entries for query :fulltext:image
> 19:01:30.675 DEBUG [main] PerfLogger.java:179 0 ... [took 0ms]
> 19:01:30.676 DEBUG [main] PerfLogger.java:179 facets retrieved
> [took 0ms]
> 19:01:30.676 DEBUG [main] QueryEngineImpl.java:154 Parsing JCR-SQL2
> statement: select [jcr:path] from [oak:TestNode] where contains(*, 'jpg')
> 19:01:30.676 DEBUG [main] QueryImpl.java:1066 cost using filter
> Filter(query=select [jcr:path] from [oak:TestNode] where contains(*, 'jpg')
> fullText="jpg", path=*)
> 19:01:30.677 DEBUG [main] QueryImpl.java:1066 cost for nodeType
> is Infinity
> 19:01:30.677 DEBUG [main] IndexPlanner.java:486 Applicable
> IndexingRule found IndexRule: oak:TestNode
> 19:01:30.677 DEBUG [main] QueryImpl.java:1066 cost for
> lucene-property[/oak:index/test1] is 2.0
> 19:01:30.678 DEBUG [main] QueryEngineImpl.java:299 No alternatives
> found. Query: select [oak:TestNode].[jcr:path] as [jcr:path] from
> [oak:TestNode] as [oak:TestNode] where contains([oak:TestNode].[*], 'jpg')
> 19:01:30.678 DEBUG [main] QueryImpl.java:1066 query execute
> select [jcr:path] from [oak:TestNode] where contains(*, 'jpg')
> 19:01:30.679 DEBUG [main] QueryImpl.java:1066 query plan
> [oak:TestNode] as [oak:TestNode] /* lucene:test1(/oak:index/test1)
> :fulltext:jpg ft:("jpg") where contains([oak:TestNode].[*], 'jpg') */
> 19:01:30.680 DEBUG [main] LucenePropertyIndex.java:404 loading the first
> 50 entries for query :fulltext:jpg
> 19:01:30.681 DEBUG [main] PerfLogger.java:179 1 ... [took 0ms]
> 19:01:30.681 DEBUG [main] PerfLogger.java:179 facets retrieved
> [took 0ms]
> 19:01:30.682 DEBUG [main] LucenePropertyIndex.java:397 loading the next
> 100 entries for query :fulltext:jpg
> 19:01:30.683 DEBUG [main] PerfLogger.java:179 0 ... [took 0ms]
> 19:01:30.683 DEBUG [main] PerfLogger.java:179 facets retrieved
> [took 0ms]
> 19:01:30.683 DEBUG [main] QueryEngineImpl.java:154 Parsing JCR-SQL2
> statement: explain select [jcr:path] from [oak:TestNode] where
> [original/jcr:content/type] = 'foo'
> 19:01:30.684 DEBUG [main] QueryImpl.java:1066 cost using filter
> Filter(query=explain select [jcr:path] from [oak:TestNode] where
> [original/jcr:content/type] = 'foo', path=*,
> property=[original/jcr:content/type=[foo]])
> 19:01:30.684 DEBUG [pool-25-thread-1] PerfLogger.java:179
> [/oak:index/test1] Copied file segments_2 of size 81 B [took 25ms]
> 19:01:30.684 DEBUG [main] QueryImpl.java:1066 cost for nodeType
> is 5.0
> 19:01:30.684 INFO [pool-25-thread-1] IndexCopier.java:426
> [java.lang.Thread.getStackTrace(Thread.java:1567),
> org.apache.jackrabbit.oak.plugins.index.lucene.IndexCopier$CopyOnReadDirectory.copyFilesToLocal(IndexCopier.java:426),
>
> org.apache.jackrabbit.oak.plugins.index.lucene.IndexCopier$CopyOnReadDirectory.access$600(IndexCopier.java:300),
>
> org.apache.jackrabbit.oak.plugins.index.lucene.IndexCopier$CopyOnReadDirectory$1.run(IndexCopier.java:397),
>
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145),
>
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615),
> java.lang.Thread.run(Thread.java:722)]
> 19:01:30.684 DEBUG [main] IndexPlanner.java:486 Applicable
> IndexingRule found IndexRule: oak:TestNode
> 19:01:30.685 DEBUG [main] QueryImpl.java:1066 cost for
> lucene-property[/oak:index/test1] is 2.0
> 19:01:30.685 DEBUG [main] QueryEngineImpl.java:299 No alternatives
> found. Query: select [oak:TestNode].[jcr:path] as [jcr:path] from
> [oak:TestNode] as [oak:TestNode] where
> [oak:TestNode].[original/jcr:content/type] = 'foo'
> 19:01:30.686 INFO [main] MyTemporaryFolder.java:64 before delete
> LucenePropertyIndexTest_aggregateAndIncludeRelativePropertyByDefault
> 19:01:30.697 DEBUG [pool-25-thread-2] PerfLogger.java:179
> [/oak:index/test1] Copied file _0.si of size 257 B [took 31ms]
> 19:01:30.697 INFO [pool-25-thread-2] IndexCopier.java:426
> [java.lang.Thread.getStackTrace(Thread.java:1567),
> org.apache.jackrabbit.oak.plugins.index.lucene.IndexCopier$CopyOnReadDirectory.copyFilesToLocal(IndexCopier.java:426),
>
> org.apache.jackrabbit.oak.plugins.index.lucene.IndexCopier$CopyOnReadDirectory.access$600(IndexCopier.java:300),
>
> org.apache.jackrabbit.oak.plugins.index.lucene.IndexCopier$CopyOnReadDirectory$1.run(IndexCopier.java:397),
>
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145),
>
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615),
> java.lang.Thread.run(Thread.java:722)]
> 19:01:30.699 INFO [main] MyTemporaryFolder.java:66 after delete
> LucenePropertyIndexTest_aggregateAndIncludeRelativePropertyByDefault...
> folder exists: true
> {noformat}
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
