You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2016/10/25 08:46:59 UTC
[JENKINS] Lucene-Solr-Tests-6.x - Build # 511 - Unstable
Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/511/
3 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.core.TestLazyCores
Error Message:
ObjectTracker found 5 object(s) that were not released!!! [MockDirectoryWrapper, MockDirectoryWrapper, MDCAwareThreadPoolExecutor, MockDirectoryWrapper, SolrCore] org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43) at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347) at org.apache.solr.core.SolrCore.getNewIndexDir(SolrCore.java:333) at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:640) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:848) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:774) at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842) at org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43) at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347) at org.apache.solr.core.SolrCore.initSnapshotMetaDataManager(SolrCore.java:435) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:842) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:774) at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842) at org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:797) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:774) at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842) at org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43) at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347) at org.apache.solr.update.SolrIndexWriter.create(SolrIndexWriter.java:66) at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:672) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:848) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:774) at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842) at org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:938) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:774) at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842) at org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745)
Stack Trace:
java.lang.AssertionError: ObjectTracker found 5 object(s) that were not released!!! [MockDirectoryWrapper, MockDirectoryWrapper, MDCAwareThreadPoolExecutor, MockDirectoryWrapper, SolrCore]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
at org.apache.solr.core.SolrCore.getNewIndexDir(SolrCore.java:333)
at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:640)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:848)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:774)
at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842)
at org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
at org.apache.solr.core.SolrCore.initSnapshotMetaDataManager(SolrCore.java:435)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:842)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:774)
at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842)
at org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:797)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:774)
at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842)
at org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
at org.apache.solr.update.SolrIndexWriter.create(SolrIndexWriter.java:66)
at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:672)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:848)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:774)
at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842)
at org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:938)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:774)
at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842)
at org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([A298337C9F5F3030]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:261)
at sun.reflect.GeneratedMethodAccessor46.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:870)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: junit.framework.TestSuite.org.apache.solr.core.TestLazyCores
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores: 1) Thread[id=5930, name=searcherExecutor-1912-thread-1, state=WAITING, group=TGRP-TestLazyCores] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores:
1) Thread[id=5930, name=searcherExecutor-1912-thread-1, state=WAITING, group=TGRP-TestLazyCores]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([A298337C9F5F3030]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.core.TestLazyCores
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=5930, name=searcherExecutor-1912-thread-1, state=WAITING, group=TGRP-TestLazyCores] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=5930, name=searcherExecutor-1912-thread-1, state=WAITING, group=TGRP-TestLazyCores]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([A298337C9F5F3030]:0)
Build Log:
[...truncated 11374 lines...]
[junit4] Suite: org.apache.solr.core.TestLazyCores
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/init-core-data-001
[junit4] 2> 520728 INFO (SUITE-TestLazyCores-seed#[A298337C9F5F3030]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 520733 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMidUseUnload
[junit4] 2> 520754 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 520755 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.c.SolrCores Allocating transient cache for 4 transient cores
[junit4] 2> 520772 WARN (coreLoadExecutor-1740-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 520773 INFO (coreLoadExecutor-1740-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 520780 WARN (coreLoadExecutor-1740-thread-3) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 520784 WARN (coreLoadExecutor-1740-thread-2) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 520785 INFO (coreLoadExecutor-1740-thread-3) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 520785 INFO (coreLoadExecutor-1740-thread-2) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 520783 INFO (coreLoadExecutor-1740-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=tiny
[junit4] 2> 520791 INFO (coreLoadExecutor-1740-thread-2) [ ] o.a.s.s.IndexSchema [collection2] Schema name=tiny
[junit4] 2> 520793 INFO (coreLoadExecutor-1740-thread-2) [ ] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 520794 INFO (coreLoadExecutor-1740-thread-2) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection2' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-001/collection2
[junit4] 2> 520794 INFO (coreLoadExecutor-1740-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 520794 INFO (coreLoadExecutor-1740-thread-2) [ x:collection2] o.a.s.c.SolrCore [[collection2] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-001/collection2], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-001/collection2/data/]
[junit4] 2> 520795 INFO (coreLoadExecutor-1740-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-001/collection1
[junit4] 2> 520795 INFO (coreLoadExecutor-1740-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-001/collection1/data/]
[junit4] 2> 520795 INFO (coreLoadExecutor-1740-thread-2) [ x:collection2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=62.8466796875, floorSegmentMB=1.0966796875, forceMergeDeletesPctAllowed=2.782099287054222, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8296379444038159
[junit4] 2> 520800 INFO (coreLoadExecutor-1740-thread-3) [ ] o.a.s.s.IndexSchema [collection5] Schema name=tiny
[junit4] 2> 520802 INFO (coreLoadExecutor-1740-thread-3) [ ] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 520803 INFO (coreLoadExecutor-1740-thread-3) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection5' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-001/collection5
[junit4] 2> 520803 INFO (coreLoadExecutor-1740-thread-3) [ x:collection5] o.a.s.c.SolrCore [[collection5] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-001/collection5], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-001/collection5/data/]
[junit4] 2> 520804 INFO (coreLoadExecutor-1740-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=62.8466796875, floorSegmentMB=1.0966796875, forceMergeDeletesPctAllowed=2.782099287054222, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8296379444038159
[junit4] 2> 520804 INFO (coreLoadExecutor-1740-thread-3) [ x:collection5] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=62.8466796875, floorSegmentMB=1.0966796875, forceMergeDeletesPctAllowed=2.782099287054222, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8296379444038159
[junit4] 2> 520808 INFO (coreLoadExecutor-1740-thread-2) [ x:collection2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 520808 INFO (coreLoadExecutor-1740-thread-2) [ x:collection2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 520808 INFO (coreLoadExecutor-1740-thread-2) [ x:collection2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=70.9013671875, floorSegmentMB=1.646484375, forceMergeDeletesPctAllowed=9.996556672073854, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7370468408512296
[junit4] 2> 520808 INFO (coreLoadExecutor-1740-thread-2) [ x:collection2] o.a.s.s.SolrIndexSearcher Opening [Searcher@b232376[collection2] main]
[junit4] 2> 520808 INFO (coreLoadExecutor-1740-thread-2) [ x:collection2] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-001/collection2/conf
[junit4] 2> 520809 INFO (coreLoadExecutor-1740-thread-2) [ x:collection2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 520809 INFO (searcherExecutor-1741-thread-1-processing-x:collection2) [ x:collection2] o.a.s.c.SolrCore [collection2] Registered new searcher Searcher@b232376[collection2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 520815 INFO (coreLoadExecutor-1740-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 520815 INFO (coreLoadExecutor-1740-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 520815 INFO (coreLoadExecutor-1740-thread-3) [ x:collection5] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 520815 INFO (coreLoadExecutor-1740-thread-3) [ x:collection5] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 520815 INFO (coreLoadExecutor-1740-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=70.9013671875, floorSegmentMB=1.646484375, forceMergeDeletesPctAllowed=9.996556672073854, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7370468408512296
[junit4] 2> 520815 INFO (coreLoadExecutor-1740-thread-3) [ x:collection5] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=70.9013671875, floorSegmentMB=1.646484375, forceMergeDeletesPctAllowed=9.996556672073854, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7370468408512296
[junit4] 2> 520821 INFO (coreLoadExecutor-1740-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@514a077[collection1] main]
[junit4] 2> 520822 INFO (coreLoadExecutor-1740-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-001/collection1/conf
[junit4] 2> 520822 INFO (coreLoadExecutor-1740-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 520825 INFO (coreLoadExecutor-1740-thread-3) [ x:collection5] o.a.s.s.SolrIndexSearcher Opening [Searcher@6fd6d111[collection5] main]
[junit4] 2> 520825 INFO (coreLoadExecutor-1740-thread-3) [ x:collection5] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-001/collection5/conf
[junit4] 2> 520826 INFO (coreLoadExecutor-1740-thread-3) [ x:collection5] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 520809 INFO (coreLoadExecutor-1740-thread-2) [ x:collection2] o.a.s.c.SolrCores Opening transient core collection2
[junit4] 2> 520828 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.c.SolrCore Core collection1 is not yet closed, waiting 100 ms before checking again.
[junit4] 2> 520831 INFO (searcherExecutor-1744-thread-1-processing-x:collection5) [ x:collection5] o.a.s.c.SolrCore [collection5] Registered new searcher Searcher@6fd6d111[collection5] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 520831 INFO (searcherExecutor-1742-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@514a077[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 520928 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.c.SolrCore Core collection1 is not yet closed, waiting 100 ms before checking again.
[junit4] 2> 521028 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.c.SolrCore Core collection1 is not yet closed, waiting 100 ms before checking again.
[junit4] 2> 521128 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.c.SolrCore Core collection1 is not yet closed, waiting 100 ms before checking again.
[junit4] 2> 521229 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.c.SolrCore Core collection1 is not yet closed, waiting 100 ms before checking again.
[junit4] 2> 521329 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.c.SolrCore Core collection1 is not yet closed, waiting 100 ms before checking again.
[junit4] 2> 521429 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.c.SolrCore Core collection1 is not yet closed, waiting 100 ms before checking again.
[junit4] 2> 521529 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.c.SolrCore Core collection1 is not yet closed, waiting 100 ms before checking again.
[junit4] 2> 521629 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.c.SolrCore Core collection1 is not yet closed, waiting 100 ms before checking again.
[junit4] 2> 521730 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.c.SolrCore Core collection1 is not yet closed, waiting 100 ms before checking again.
[junit4] 2> 521830 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.c.SolrCore Core collection1 is not yet closed, waiting 100 ms before checking again.
[junit4] 2> 521930 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.c.SolrCore Core collection1 is not yet closed, waiting 100 ms before checking again.
[junit4] 2> 522030 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.c.SolrCore Core collection1 is not yet closed, waiting 100 ms before checking again.
[junit4] 2> 522130 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.c.SolrCore Core collection1 is not yet closed, waiting 100 ms before checking again.
[junit4] 2> 522231 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.c.SolrCore Core collection1 is not yet closed, waiting 100 ms before checking again.
[junit4] 2> 522331 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.c.SolrCore Core collection1 is not yet closed, waiting 100 ms before checking again.
[junit4] 2> 522431 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.c.SolrCore Core collection1 is not yet closed, waiting 100 ms before checking again.
[junit4] 2> 522531 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.c.SolrCore Core collection1 is not yet closed, waiting 100 ms before checking again.
[junit4] 2> 522615 INFO (Thread-2862) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3d54613
[junit4] 2> 522631 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=45265826
[junit4] 2> 522632 INFO (coreCloseExecutor-1754-thread-1) [ x:collection5] o.a.s.c.SolrCore [collection5] CLOSING SolrCore org.apache.solr.core.SolrCore@1f388231
[junit4] 2> 522632 INFO (coreCloseExecutor-1754-thread-2) [ x:collection2] o.a.s.c.SolrCore [collection2] CLOSING SolrCore org.apache.solr.core.SolrCore@624fd540
[junit4] 2> 522633 INFO (TEST-TestLazyCores.testMidUseUnload-seed#[A298337C9F5F3030]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMidUseUnload
[junit4] 2> 522636 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateSame
[junit4] 2> 522647 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 522647 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ ] o.a.s.c.SolrCores Allocating transient cache for 4 transient cores
[junit4] 2> 522656 WARN (coreLoadExecutor-1761-thread-3) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 522656 WARN (coreLoadExecutor-1761-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 522656 WARN (coreLoadExecutor-1761-thread-2) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 522657 INFO (coreLoadExecutor-1761-thread-3) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 522657 INFO (coreLoadExecutor-1761-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 522657 INFO (coreLoadExecutor-1761-thread-2) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 522676 INFO (coreLoadExecutor-1761-thread-3) [ ] o.a.s.s.IndexSchema [collection5] Schema name=tiny
[junit4] 2> 522676 INFO (coreLoadExecutor-1761-thread-2) [ ] o.a.s.s.IndexSchema [collection2] Schema name=tiny
[junit4] 2> 522676 INFO (coreLoadExecutor-1761-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=tiny
[junit4] 2> 522679 INFO (coreLoadExecutor-1761-thread-2) [ ] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 522679 INFO (coreLoadExecutor-1761-thread-3) [ ] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 522679 INFO (coreLoadExecutor-1761-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 522680 INFO (coreLoadExecutor-1761-thread-2) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection2' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-002/collection2
[junit4] 2> 522680 INFO (coreLoadExecutor-1761-thread-3) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection5' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-002/collection5
[junit4] 2> 522680 INFO (coreLoadExecutor-1761-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-002/collection1
[junit4] 2> 522680 INFO (coreLoadExecutor-1761-thread-2) [ x:collection2] o.a.s.c.SolrCore [[collection2] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-002/collection2], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-002/collection2/data/]
[junit4] 2> 522680 INFO (coreLoadExecutor-1761-thread-3) [ x:collection5] o.a.s.c.SolrCore [[collection5] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-002/collection5], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-002/collection5/data/]
[junit4] 2> 522680 INFO (coreLoadExecutor-1761-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-002/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-002/collection1/data/]
[junit4] 2> 522681 INFO (coreLoadExecutor-1761-thread-3) [ x:collection5] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=62.8466796875, floorSegmentMB=1.0966796875, forceMergeDeletesPctAllowed=2.782099287054222, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8296379444038159
[junit4] 2> 522681 INFO (coreLoadExecutor-1761-thread-2) [ x:collection2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=62.8466796875, floorSegmentMB=1.0966796875, forceMergeDeletesPctAllowed=2.782099287054222, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8296379444038159
[junit4] 2> 522681 INFO (coreLoadExecutor-1761-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=62.8466796875, floorSegmentMB=1.0966796875, forceMergeDeletesPctAllowed=2.782099287054222, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8296379444038159
[junit4] 2> 522687 INFO (coreLoadExecutor-1761-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 522687 INFO (coreLoadExecutor-1761-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 522687 INFO (coreLoadExecutor-1761-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=70.9013671875, floorSegmentMB=1.646484375, forceMergeDeletesPctAllowed=9.996556672073854, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7370468408512296
[junit4] 2> 522687 INFO (coreLoadExecutor-1761-thread-2) [ x:collection2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 522687 INFO (coreLoadExecutor-1761-thread-2) [ x:collection2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 522687 INFO (coreLoadExecutor-1761-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5ede3ce3[collection1] main]
[junit4] 2> 522687 INFO (coreLoadExecutor-1761-thread-2) [ x:collection2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=70.9013671875, floorSegmentMB=1.646484375, forceMergeDeletesPctAllowed=9.996556672073854, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7370468408512296
[junit4] 2> 522687 INFO (coreLoadExecutor-1761-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-002/collection1/conf
[junit4] 2> 522687 INFO (coreLoadExecutor-1761-thread-2) [ x:collection2] o.a.s.s.SolrIndexSearcher Opening [Searcher@37fdb183[collection2] main]
[junit4] 2> 522687 INFO (coreLoadExecutor-1761-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 522687 INFO (coreLoadExecutor-1761-thread-2) [ x:collection2] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-002/collection2/conf
[junit4] 2> 522688 INFO (coreLoadExecutor-1761-thread-2) [ x:collection2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 522688 INFO (searcherExecutor-1762-thread-1-processing-x:collection2) [ x:collection2] o.a.s.c.SolrCore [collection2] Registered new searcher Searcher@37fdb183[collection2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 522688 INFO (coreLoadExecutor-1761-thread-2) [ x:collection2] o.a.s.c.SolrCores Opening transient core collection2
[junit4] 2> 522689 INFO (coreLoadExecutor-1761-thread-3) [ x:collection5] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 522689 INFO (searcherExecutor-1764-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5ede3ce3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 522689 INFO (coreLoadExecutor-1761-thread-3) [ x:collection5] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 522689 INFO (coreLoadExecutor-1761-thread-3) [ x:collection5] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=70.9013671875, floorSegmentMB=1.646484375, forceMergeDeletesPctAllowed=9.996556672073854, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7370468408512296
[junit4] 2> 522689 INFO (coreLoadExecutor-1761-thread-3) [ x:collection5] o.a.s.s.SolrIndexSearcher Opening [Searcher@3105c33a[collection5] main]
[junit4] 2> 522690 INFO (coreLoadExecutor-1761-thread-3) [ x:collection5] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-002/collection5/conf
[junit4] 2> 522690 INFO (coreLoadExecutor-1761-thread-3) [ x:collection5] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 522695 INFO (searcherExecutor-1763-thread-1-processing-x:collection5) [ x:collection5] o.a.s.c.SolrCore [collection5] Registered new searcher Searcher@3105c33a[collection5] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 522697 WARN (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 522697 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 522703 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ ] o.a.s.s.IndexSchema [collection4] Schema name=tiny
[junit4] 2> 522705 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ ] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 522706 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection4' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-002/collection4
[junit4] 2> 522707 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection4] o.a.s.c.SolrCore [[collection4] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-002/collection4], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-002/collection4/data/]
[junit4] 2> 522708 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection4] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=1.3623046875, floorSegmentMB=0.87109375, forceMergeDeletesPctAllowed=9.89906987830357, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.13003139356376653
[junit4] 2> 522713 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection4] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 522713 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection4] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 522713 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection4] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=36.8388671875, floorSegmentMB=1.3310546875, forceMergeDeletesPctAllowed=29.52083576465789, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 522713 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection4] o.a.s.s.SolrIndexSearcher Opening [Searcher@3c0d07a[collection4] main]
[junit4] 2> 522714 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection4] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-002/collection4/conf
[junit4] 2> 522714 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 522714 INFO (searcherExecutor-1775-thread-1-processing-x:collection4) [ x:collection4] o.a.s.c.SolrCore [collection4] Registered new searcher Searcher@3c0d07a[collection4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 522720 WARN (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection4] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 522720 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 522726 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection4] o.a.s.s.IndexSchema [collection6] Schema name=tiny
[junit4] 2> 522728 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection4] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 522729 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection4] o.a.s.c.CoreContainer Creating SolrCore 'collection6' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-002/collection6
[junit4] 2> 522729 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.c.SolrCore [[collection6] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-002/collection6], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-002/collection6/data/]
[junit4] 2> 522730 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=41, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 522736 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 522736 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 522736 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 522736 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.s.SolrIndexSearcher Opening [Searcher@2ad83c95[collection6] main]
[junit4] 2> 522736 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-002/collection6/conf
[junit4] 2> 522736 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 522737 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.c.SolrCores Opening transient core collection6
[junit4] 2> 522737 INFO (searcherExecutor-1779-thread-1-processing-x:collection6) [ x:collection6] o.a.s.c.SolrCore [collection6] Registered new searcher Searcher@2ad83c95[collection6] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 522741 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.h.a.CoreAdminOperation core create command schema=schema.xml&dataDir=t12&name=collection2&action=CREATE&config=solrconfig.xml&wt=xml
[junit4] 2> 522741 WARN (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.c.CoreContainer Creating a core with existing name is not allowed
[junit4] 2> 522742 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.h.a.CoreAdminOperation core create command schema=schema.xml&dataDir=t14&name=collection4&action=CREATE&config=solrconfig.xml&wt=xml
[junit4] 2> 522742 WARN (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.c.CoreContainer Creating a core with existing name is not allowed
[junit4] 2> 522742 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.h.a.CoreAdminOperation core create command schema=schema.xml&dataDir=t15&name=collection5&action=CREATE&config=solrconfig.xml&wt=xml
[junit4] 2> 522742 WARN (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.c.CoreContainer Creating a core with existing name is not allowed
[junit4] 2> 522742 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.h.a.CoreAdminOperation core create command schema=schema.xml&dataDir=t16&name=collection6&action=CREATE&config=solrconfig.xml&wt=xml
[junit4] 2> 522742 WARN (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.c.CoreContainer Creating a core with existing name is not allowed
[junit4] 2> 522742 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.c.CoreContainer Shutting down CoreContainer instance=687705365
[junit4] 2> 522743 INFO (coreCloseExecutor-1783-thread-1-processing-x:collection6) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@449fbffb
[junit4] 2> 522744 INFO (coreCloseExecutor-1783-thread-2-processing-x:collection6) [ x:collection5] o.a.s.c.SolrCore [collection5] CLOSING SolrCore org.apache.solr.core.SolrCore@53b1265f
[junit4] 2> 522745 INFO (coreCloseExecutor-1783-thread-4-processing-x:collection6) [ x:collection2] o.a.s.c.SolrCore [collection2] CLOSING SolrCore org.apache.solr.core.SolrCore@1b980e2e
[junit4] 2> 522745 INFO (coreCloseExecutor-1783-thread-3-processing-x:collection6) [ x:collection4] o.a.s.c.SolrCore [collection4] CLOSING SolrCore org.apache.solr.core.SolrCore@368a2faf
[junit4] 2> 522746 INFO (coreCloseExecutor-1783-thread-5-processing-x:collection6) [ x:collection6] o.a.s.c.SolrCore [collection6] CLOSING SolrCore org.apache.solr.core.SolrCore@5cc9d510
[junit4] 2> 522747 INFO (TEST-TestLazyCores.testCreateSame-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.SolrTestCaseJ4 ###Ending testCreateSame
[junit4] 2> 522749 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.SolrTestCaseJ4 ###Starting testCreateTransientFromAdmin
[junit4] 2> 522761 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 522761 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.c.SolrCores Allocating transient cache for 4 transient cores
[junit4] 2> 522779 WARN (coreLoadExecutor-1790-thread-2-processing-x:collection6) [ x:collection6] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 522779 WARN (coreLoadExecutor-1790-thread-1-processing-x:collection6) [ x:collection6] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 522779 WARN (coreLoadExecutor-1790-thread-3-processing-x:collection6) [ x:collection6] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 522780 INFO (coreLoadExecutor-1790-thread-2-processing-x:collection6) [ x:collection6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 522780 INFO (coreLoadExecutor-1790-thread-1-processing-x:collection6) [ x:collection6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 522780 INFO (coreLoadExecutor-1790-thread-3-processing-x:collection6) [ x:collection6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 522786 INFO (coreLoadExecutor-1790-thread-2-processing-x:collection6) [ x:collection6] o.a.s.s.IndexSchema [collection2] Schema name=tiny
[junit4] 2> 522788 INFO (coreLoadExecutor-1790-thread-2-processing-x:collection6) [ x:collection6] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 522789 INFO (coreLoadExecutor-1790-thread-2-processing-x:collection6) [ x:collection6] o.a.s.c.CoreContainer Creating SolrCore 'collection2' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/collection2
[junit4] 2> 522790 INFO (coreLoadExecutor-1790-thread-3-processing-x:collection6) [ x:collection6] o.a.s.s.IndexSchema [collection5] Schema name=tiny
[junit4] 2> 522790 INFO (coreLoadExecutor-1790-thread-2-processing-x:collection6) [ x:collection2] o.a.s.c.SolrCore [[collection2] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/collection2], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/collection2/data/]
[junit4] 2> 522791 INFO (coreLoadExecutor-1790-thread-2-processing-x:collection6) [ x:collection2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=62.8466796875, floorSegmentMB=1.0966796875, forceMergeDeletesPctAllowed=2.782099287054222, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8296379444038159
[junit4] 2> 522792 INFO (coreLoadExecutor-1790-thread-1-processing-x:collection6) [ x:collection6] o.a.s.s.IndexSchema [collection1] Schema name=tiny
[junit4] 2> 522792 INFO (coreLoadExecutor-1790-thread-3-processing-x:collection6) [ x:collection6] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 522793 INFO (coreLoadExecutor-1790-thread-3-processing-x:collection6) [ x:collection6] o.a.s.c.CoreContainer Creating SolrCore 'collection5' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/collection5
[junit4] 2> 522793 INFO (coreLoadExecutor-1790-thread-3-processing-x:collection6) [ x:collection5] o.a.s.c.SolrCore [[collection5] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/collection5], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/collection5/data/]
[junit4] 2> 522793 INFO (coreLoadExecutor-1790-thread-1-processing-x:collection6) [ x:collection6] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 522794 INFO (coreLoadExecutor-1790-thread-1-processing-x:collection6) [ x:collection6] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/collection1
[junit4] 2> 522794 INFO (coreLoadExecutor-1790-thread-1-processing-x:collection6) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/collection1/data/]
[junit4] 2> 522795 INFO (coreLoadExecutor-1790-thread-1-processing-x:collection6) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=62.8466796875, floorSegmentMB=1.0966796875, forceMergeDeletesPctAllowed=2.782099287054222, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8296379444038159
[junit4] 2> 522796 INFO (coreLoadExecutor-1790-thread-3-processing-x:collection6) [ x:collection5] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=62.8466796875, floorSegmentMB=1.0966796875, forceMergeDeletesPctAllowed=2.782099287054222, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8296379444038159
[junit4] 2> 522800 INFO (coreLoadExecutor-1790-thread-1-processing-x:collection6) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 522801 INFO (coreLoadExecutor-1790-thread-1-processing-x:collection6) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 522801 INFO (coreLoadExecutor-1790-thread-2-processing-x:collection6) [ x:collection2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 522801 INFO (coreLoadExecutor-1790-thread-1-processing-x:collection6) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=70.9013671875, floorSegmentMB=1.646484375, forceMergeDeletesPctAllowed=9.996556672073854, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7370468408512296
[junit4] 2> 522801 INFO (coreLoadExecutor-1790-thread-3-processing-x:collection6) [ x:collection5] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 522801 INFO (coreLoadExecutor-1790-thread-3-processing-x:collection6) [ x:collection5] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 522801 INFO (coreLoadExecutor-1790-thread-3-processing-x:collection6) [ x:collection5] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=70.9013671875, floorSegmentMB=1.646484375, forceMergeDeletesPctAllowed=9.996556672073854, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7370468408512296
[junit4] 2> 522801 INFO (coreLoadExecutor-1790-thread-1-processing-x:collection6) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@b37e579[collection1] main]
[junit4] 2> 522801 INFO (coreLoadExecutor-1790-thread-2-processing-x:collection6) [ x:collection2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 522801 INFO (coreLoadExecutor-1790-thread-2-processing-x:collection6) [ x:collection2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=70.9013671875, floorSegmentMB=1.646484375, forceMergeDeletesPctAllowed=9.996556672073854, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7370468408512296
[junit4] 2> 522801 INFO (coreLoadExecutor-1790-thread-1-processing-x:collection6) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/collection1/conf
[junit4] 2> 522801 INFO (coreLoadExecutor-1790-thread-3-processing-x:collection6) [ x:collection5] o.a.s.s.SolrIndexSearcher Opening [Searcher@342330e3[collection5] main]
[junit4] 2> 522801 INFO (coreLoadExecutor-1790-thread-3-processing-x:collection6) [ x:collection5] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/collection5/conf
[junit4] 2> 522802 INFO (coreLoadExecutor-1790-thread-3-processing-x:collection6) [ x:collection5] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 522802 INFO (coreLoadExecutor-1790-thread-1-processing-x:collection6) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 522802 INFO (searcherExecutor-1793-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@b37e579[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 522802 INFO (coreLoadExecutor-1790-thread-2-processing-x:collection6) [ x:collection2] o.a.s.s.SolrIndexSearcher Opening [Searcher@188d5b12[collection2] main]
[junit4] 2> 522802 INFO (coreLoadExecutor-1790-thread-2-processing-x:collection6) [ x:collection2] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/collection2/conf
[junit4] 2> 522802 INFO (coreLoadExecutor-1790-thread-2-processing-x:collection6) [ x:collection2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 522802 INFO (coreLoadExecutor-1790-thread-2-processing-x:collection6) [ x:collection2] o.a.s.c.SolrCores Opening transient core collection2
[junit4] 2> 522803 INFO (searcherExecutor-1791-thread-1-processing-x:collection2) [ x:collection2] o.a.s.c.SolrCore [collection2] Registered new searcher Searcher@188d5b12[collection2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 522803 INFO (searcherExecutor-1792-thread-1-processing-x:collection5) [ x:collection5] o.a.s.c.SolrCore [collection5] Registered new searcher Searcher@342330e3[collection5] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 522808 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.h.a.CoreAdminOperation core create command loadOnStartup=true&transient=true&name=core1&action=CREATE&wt=xml
[junit4] 2> 522813 WARN (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 522813 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 522819 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.s.IndexSchema [core1] Schema name=tiny
[junit4] 2> 522821 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 522822 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:collection6] o.a.s.c.CoreContainer Creating SolrCore 'core1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core1
[junit4] 2> 522822 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core1] o.a.s.c.SolrCore [[core1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core1/data/]
[junit4] 2> 522823 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=27.908203125, floorSegmentMB=0.9560546875, forceMergeDeletesPctAllowed=3.688130579842306, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 522827 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 522827 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 522828 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=27, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 522828 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core1] o.a.s.s.SolrIndexSearcher Opening [Searcher@f594703[core1] main]
[junit4] 2> 522828 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core1/conf
[junit4] 2> 522828 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 522828 INFO (searcherExecutor-1804-thread-1-processing-x:core1) [ x:core1] o.a.s.c.SolrCore [core1] Registered new searcher Searcher@f594703[core1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 522828 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core1] o.a.s.c.SolrCores Opening transient core core1
[junit4] 2> 522829 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core1] o.a.s.h.a.CoreAdminOperation core create command loadOnStartup=false&transient=true&name=core2&action=CREATE&wt=xml
[junit4] 2> 522834 WARN (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 522834 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 522840 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core1] o.a.s.s.IndexSchema [core2] Schema name=tiny
[junit4] 2> 522842 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core1] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 522842 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core1] o.a.s.c.CoreContainer Creating SolrCore 'core2' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core2
[junit4] 2> 522843 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core2] o.a.s.c.SolrCore [[core2] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core2], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core2/data/]
[junit4] 2> 522844 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=52263180, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 522848 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 522848 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 522848 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=68.32421875, floorSegmentMB=0.6181640625, forceMergeDeletesPctAllowed=15.973239311052032, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 522848 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core2] o.a.s.s.SolrIndexSearcher Opening [Searcher@46b0423b[core2] main]
[junit4] 2> 522848 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core2] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core2/conf
[junit4] 2> 522849 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 522849 INFO (searcherExecutor-1809-thread-1-processing-x:core2) [ x:core2] o.a.s.c.SolrCore [core2] Registered new searcher Searcher@46b0423b[core2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 522849 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core2] o.a.s.c.SolrCores Opening transient core core2
[junit4] 2> 522849 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core2] o.a.s.h.a.CoreAdminOperation core create command loadOnStartup=true&transient=true&name=core3&action=CREATE&wt=xml
[junit4] 2> 522860 WARN (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core2] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 522861 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 522866 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core2] o.a.s.s.IndexSchema [core3] Schema name=tiny
[junit4] 2> 522868 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core2] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 522869 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core2] o.a.s.c.CoreContainer Creating SolrCore 'core3' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core3
[junit4] 2> 522869 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core3] o.a.s.c.SolrCore [[core3] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core3], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core3/data/]
[junit4] 2> 522871 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core3] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=13.18359375, floorSegmentMB=1.1806640625, forceMergeDeletesPctAllowed=12.38784331969721, segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.16526306586731038
[junit4] 2> 522875 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core3] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 522875 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core3] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 522875 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core3] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=8.71484375, floorSegmentMB=1.3310546875, forceMergeDeletesPctAllowed=18.2916516133708, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8535961203082908
[junit4] 2> 522875 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core3] o.a.s.s.SolrIndexSearcher Opening [Searcher@21bef19f[core3] main]
[junit4] 2> 522875 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core3] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core3/conf
[junit4] 2> 522876 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core3] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 522876 INFO (searcherExecutor-1814-thread-1-processing-x:core3) [ x:core3] o.a.s.c.SolrCore [core3] Registered new searcher Searcher@21bef19f[core3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 522876 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core3] o.a.s.c.SolrCores Opening transient core core3
[junit4] 2> 522876 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core3] o.a.s.h.a.CoreAdminOperation core create command loadOnStartup=false&transient=true&name=core4&action=CREATE&wt=xml
[junit4] 2> 522881 WARN (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core3] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 522882 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 522888 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core3] o.a.s.s.IndexSchema [core4] Schema name=tiny
[junit4] 2> 522889 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core3] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 522890 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core3] o.a.s.c.CoreContainer Creating SolrCore 'core4' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core4
[junit4] 2> 522891 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core4] o.a.s.c.SolrCore [[core4] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core4], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core4/data/]
[junit4] 2> 522891 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core4] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=63.4091796875, floorSegmentMB=0.9306640625, forceMergeDeletesPctAllowed=19.761297922687014, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 522896 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core4] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 522896 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core4] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 522896 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core4] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=35.3095703125, floorSegmentMB=2.0380859375, forceMergeDeletesPctAllowed=24.044570846416104, segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 522896 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core4] o.a.s.s.SolrIndexSearcher Opening [Searcher@5af083e2[core4] main]
[junit4] 2> 522896 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core4] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core4/conf
[junit4] 2> 522896 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 522897 INFO (searcherExecutor-1819-thread-1-processing-x:core4) [ x:core4] o.a.s.c.SolrCore [core4] Registered new searcher Searcher@5af083e2[core4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 522897 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core4] o.a.s.c.SolrCores Opening transient core core4
[junit4] 2> 522897 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core4] o.a.s.c.SolrCores Closing transient core [collection2]
[junit4] 2> 522897 INFO (Thread-2864) [ x:collection6] o.a.s.c.SolrCore [collection2] CLOSING SolrCore org.apache.solr.core.SolrCore@34867023
[junit4] 2> 522898 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core4] o.a.s.h.a.CoreAdminOperation core create command loadOnStartup=false&transient=true&name=core5&action=CREATE&wt=xml
[junit4] 2> 522903 WARN (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core4] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 522904 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 522910 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core4] o.a.s.s.IndexSchema [core5] Schema name=tiny
[junit4] 2> 522912 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core4] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 522913 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core4] o.a.s.c.CoreContainer Creating SolrCore 'core5' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core5
[junit4] 2> 522913 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core5] o.a.s.c.SolrCore [[core5] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core5], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core5/data/]
[junit4] 2> 522914 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core5] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=30, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 522919 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core5] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 522919 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core5] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 522919 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core5] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=26.3935546875, floorSegmentMB=1.5810546875, forceMergeDeletesPctAllowed=23.477530028917343, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3164768844396515
[junit4] 2> 522919 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core5] o.a.s.s.SolrIndexSearcher Opening [Searcher@b40b5d6[core5] main]
[junit4] 2> 522919 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core5] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core5/conf
[junit4] 2> 522919 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core5] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 522920 INFO (searcherExecutor-1824-thread-1-processing-x:core5) [ x:core5] o.a.s.c.SolrCore [core5] Registered new searcher Searcher@b40b5d6[core5] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 522920 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core5] o.a.s.c.SolrCores Opening transient core core5
[junit4] 2> 522920 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core5] o.a.s.c.SolrCores Closing transient core [core1]
[junit4] 2> 522920 INFO (Thread-2864) [ x:collection6] o.a.s.c.SolrCore [core1] CLOSING SolrCore org.apache.solr.core.SolrCore@772b4f45
[junit4] 2> 522925 WARN (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core5] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 522926 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core5] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 522932 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core5] o.a.s.s.IndexSchema [core1] Schema name=tiny
[junit4] 2> 522934 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core5] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 522934 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core5] o.a.s.c.CoreContainer Creating SolrCore 'core1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core1
[junit4] 2> 522935 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core1] o.a.s.c.SolrCore [[core1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_A298337C9F5F3030-001/tempDir-003/core1/data/]
[junit4] 2> 522936 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=11, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=29.1591796875, floorSegmentMB=0.5341796875, forceMergeDeletesPctAllowed=22.960495595597717, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5571304512786875
[junit4] 2> 522940 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 522940 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 522940 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[A298337C9F5F3030]) [ x:core1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=77.9658203125, floorSegmentMB=1.193359375, forceMergeDeletesPctAllowed=23.048221
[...truncated too long message...]
, DistributedVersionInfoTest, CurrencyFieldOpenExchangeTest, AlternateDirectoryTest, TestShortCircuitedRequests, TestTolerantUpdateProcessorCloud, TestStressRecovery, TestConfigSetImmutable, TestReloadDeadlock, CheckHdfsIndexTest, TestComponentsName, DisMaxRequestHandlerTest, StandardRequestHandlerTest, TestQuerySenderNoQuery, ZkStateReaderTest, TestInfoStreamLogging, HdfsThreadLeakTest, ChaosMonkeyNothingIsSafeTest, TestRandomDVFaceting, HdfsLockFactoryTest, TestFastLRUCache, SyncSliceTest, HdfsRecoveryZkTest, FileBasedSpellCheckerTest, SharedFSAutoReplicaFailoverUtilsTest, DistributedSpellCheckComponentTest, PolyFieldTest, TestAuthorizationFramework, TestDocSet, AnalysisAfterCoreReloadTest, ResourceLoaderTest, TestUseDocValuesAsStored, TestRangeQuery, NumericFieldsTest, TestMaxScoreQueryParser, TestSolrCloudWithKerberosAlt, TestAnalyzeInfixSuggestions, SignatureUpdateProcessorFactoryTest, TestSmileRequest, NotRequiredUniqueKeyTest, CoreAdminCreateDiscoverTest, TestBadConfig, CdcrReplicationDistributedZkTest, LeaderElectionIntegrationTest, TestUninvertingReader, ScriptEngineTest, TemplateUpdateProcessorTest, TestLazyCores]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestLazyCores -Dtests.seed=A298337C9F5F3030 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=mk-MK -Dtests.timezone=Pacific/Rarotonga -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J0 | TestLazyCores (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 5 object(s) that were not released!!! [MockDirectoryWrapper, MockDirectoryWrapper, MDCAwareThreadPoolExecutor, MockDirectoryWrapper, SolrCore]
[junit4] > org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
[junit4] > at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
[junit4] > at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
[junit4] > at org.apache.solr.core.SolrCore.getNewIndexDir(SolrCore.java:333)
[junit4] > at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:640)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:848)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:774)
[junit4] > at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842)
[junit4] > at org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498)
[junit4] > at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
[junit4] > at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
[junit4] > at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
[junit4] > at org.apache.solr.core.SolrCore.initSnapshotMetaDataManager(SolrCore.java:435)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:842)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:774)
[junit4] > at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842)
[junit4] > at org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498)
[junit4] > at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
[junit4] > at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:797)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:774)
[junit4] > at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842)
[junit4] > at org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498)
[junit4] > at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
[junit4] > at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
[junit4] > at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
[junit4] > at org.apache.solr.update.SolrIndexWriter.create(SolrIndexWriter.java:66)
[junit4] > at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:672)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:848)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:774)
[junit4] > at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842)
[junit4] > at org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498)
[junit4] > at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
[junit4] > at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:938)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:774)
[junit4] > at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842)
[junit4] > at org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498)
[junit4] > at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at __randomizedtesting.SeedInfo.seed([A298337C9F5F3030]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:261)
[junit4] > at java.lang.Thread.run(Thread.java:745)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores:
[junit4] > 1) Thread[id=5930, name=searcherExecutor-1912-thread-1, state=WAITING, group=TGRP-TestLazyCores]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at __randomizedtesting.SeedInfo.seed([A298337C9F5F3030]:0)Throwable #3: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=5930, name=searcherExecutor-1912-thread-1, state=WAITING, group=TGRP-TestLazyCores]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at __randomizedtesting.SeedInfo.seed([A298337C9F5F3030]:0)
[junit4] Completed [230/644 (1!)] on J0 in 178.98s, 8 tests, 1 failure, 2 errors <<< FAILURES!
[...truncated 61638 lines...]
[JENKINS] Lucene-Solr-Tests-6.x - Build # 512 - Still Unstable
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/512/
6 tests failed.
FAILED: org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib {p0=DV}
Error Message:
mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
Stack Trace:
java.lang.RuntimeException: mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
at __randomizedtesting.SeedInfo.seed([219BCB0F004F08C3:FD5B62796DCEAC09]:0)
at org.apache.solr.SolrTestCaseHS.matchJSON(SolrTestCaseHS.java:161)
at org.apache.solr.SolrTestCaseHS.assertJQ(SolrTestCaseHS.java:143)
at org.apache.solr.SolrTestCaseHS$Client$Tester.assertJQ(SolrTestCaseHS.java:255)
at org.apache.solr.SolrTestCaseHS$Client.testJQ(SolrTestCaseHS.java:296)
at org.apache.solr.search.facet.TestJsonFacets.doStatsTemplated(TestJsonFacets.java:1152)
at org.apache.solr.search.facet.TestJsonFacets.doStats(TestJsonFacets.java:361)
at org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib(TestJsonFacets.java:322)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib {p0=DVHASH}
Error Message:
mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
Stack Trace:
java.lang.RuntimeException: mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
at __randomizedtesting.SeedInfo.seed([219BCB0F004F08C3:FD5B62796DCEAC09]:0)
at org.apache.solr.SolrTestCaseHS.matchJSON(SolrTestCaseHS.java:161)
at org.apache.solr.SolrTestCaseHS.assertJQ(SolrTestCaseHS.java:143)
at org.apache.solr.SolrTestCaseHS$Client$Tester.assertJQ(SolrTestCaseHS.java:255)
at org.apache.solr.SolrTestCaseHS$Client.testJQ(SolrTestCaseHS.java:296)
at org.apache.solr.search.facet.TestJsonFacets.doStatsTemplated(TestJsonFacets.java:1152)
at org.apache.solr.search.facet.TestJsonFacets.doStats(TestJsonFacets.java:361)
at org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib(TestJsonFacets.java:322)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib {p0=SMART}
Error Message:
mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
Stack Trace:
java.lang.RuntimeException: mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
at __randomizedtesting.SeedInfo.seed([219BCB0F004F08C3:FD5B62796DCEAC09]:0)
at org.apache.solr.SolrTestCaseHS.matchJSON(SolrTestCaseHS.java:161)
at org.apache.solr.SolrTestCaseHS.assertJQ(SolrTestCaseHS.java:143)
at org.apache.solr.SolrTestCaseHS$Client$Tester.assertJQ(SolrTestCaseHS.java:255)
at org.apache.solr.SolrTestCaseHS$Client.testJQ(SolrTestCaseHS.java:296)
at org.apache.solr.search.facet.TestJsonFacets.doStatsTemplated(TestJsonFacets.java:1152)
at org.apache.solr.search.facet.TestJsonFacets.doStats(TestJsonFacets.java:361)
at org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib(TestJsonFacets.java:322)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib {p0=UIF}
Error Message:
mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
Stack Trace:
java.lang.RuntimeException: mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
at __randomizedtesting.SeedInfo.seed([219BCB0F004F08C3:FD5B62796DCEAC09]:0)
at org.apache.solr.SolrTestCaseHS.matchJSON(SolrTestCaseHS.java:161)
at org.apache.solr.SolrTestCaseHS.assertJQ(SolrTestCaseHS.java:143)
at org.apache.solr.SolrTestCaseHS$Client$Tester.assertJQ(SolrTestCaseHS.java:255)
at org.apache.solr.SolrTestCaseHS$Client.testJQ(SolrTestCaseHS.java:296)
at org.apache.solr.search.facet.TestJsonFacets.doStatsTemplated(TestJsonFacets.java:1152)
at org.apache.solr.search.facet.TestJsonFacets.doStats(TestJsonFacets.java:361)
at org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib(TestJsonFacets.java:322)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib {p0=ENUM}
Error Message:
mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
Stack Trace:
java.lang.RuntimeException: mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
at __randomizedtesting.SeedInfo.seed([219BCB0F004F08C3:FD5B62796DCEAC09]:0)
at org.apache.solr.SolrTestCaseHS.matchJSON(SolrTestCaseHS.java:161)
at org.apache.solr.SolrTestCaseHS.assertJQ(SolrTestCaseHS.java:143)
at org.apache.solr.SolrTestCaseHS$Client$Tester.assertJQ(SolrTestCaseHS.java:255)
at org.apache.solr.SolrTestCaseHS$Client.testJQ(SolrTestCaseHS.java:296)
at org.apache.solr.search.facet.TestJsonFacets.doStatsTemplated(TestJsonFacets.java:1152)
at org.apache.solr.search.facet.TestJsonFacets.doStats(TestJsonFacets.java:361)
at org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib(TestJsonFacets.java:322)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib {p0=STREAM}
Error Message:
mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
Stack Trace:
java.lang.RuntimeException: mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
at __randomizedtesting.SeedInfo.seed([219BCB0F004F08C3:FD5B62796DCEAC09]:0)
at org.apache.solr.SolrTestCaseHS.matchJSON(SolrTestCaseHS.java:161)
at org.apache.solr.SolrTestCaseHS.assertJQ(SolrTestCaseHS.java:143)
at org.apache.solr.SolrTestCaseHS$Client$Tester.assertJQ(SolrTestCaseHS.java:255)
at org.apache.solr.SolrTestCaseHS$Client.testJQ(SolrTestCaseHS.java:296)
at org.apache.solr.search.facet.TestJsonFacets.doStatsTemplated(TestJsonFacets.java:1152)
at org.apache.solr.search.facet.TestJsonFacets.doStats(TestJsonFacets.java:361)
at org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib(TestJsonFacets.java:322)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 12614 lines...]
[junit4] Suite: org.apache.solr.search.facet.TestJsonFacets
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/init-core-data-001
[junit4] 2> 1732558 INFO (SUITE-TestJsonFacets-seed#[219BCB0F004F08C3]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 1732558 INFO (SUITE-TestJsonFacets-seed#[219BCB0F004F08C3]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1732559 INFO (SUITE-TestJsonFacets-seed#[219BCB0F004F08C3]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1732590 INFO (SUITE-TestJsonFacets-seed#[219BCB0F004F08C3]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1732600 INFO (SUITE-TestJsonFacets-seed#[219BCB0F004F08C3]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=example
[junit4] 2> 1732649 INFO (SUITE-TestJsonFacets-seed#[219BCB0F004F08C3]-worker) [ ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
[junit4] 2> 1732659 INFO (SUITE-TestJsonFacets-seed#[219BCB0F004F08C3]-worker) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 1732661 INFO (coreLoadExecutor-6983-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1732675 INFO (coreLoadExecutor-6983-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1732685 INFO (coreLoadExecutor-6983-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=example
[junit4] 2> 1732748 INFO (coreLoadExecutor-6983-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
[junit4] 2> 1732761 INFO (coreLoadExecutor-6983-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1
[junit4] 2> 1732761 INFO (coreLoadExecutor-6983-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/init-core-data-001/]
[junit4] 2> 1732762 INFO (coreLoadExecutor-6983-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5410ef4f
[junit4] 2> 1732762 INFO (coreLoadExecutor-6983-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=6.078125, floorSegmentMB=2.1630859375, forceMergeDeletesPctAllowed=11.645507500074006, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.37405726359010283
[junit4] 2> 1732765 WARN (coreLoadExecutor-6983-thread-1) [ x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 1732770 INFO (coreLoadExecutor-6983-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1732770 INFO (coreLoadExecutor-6983-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1732771 INFO (coreLoadExecutor-6983-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1732771 INFO (coreLoadExecutor-6983-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1732771 INFO (coreLoadExecutor-6983-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8158854530582518]
[junit4] 2> 1732771 INFO (coreLoadExecutor-6983-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2d1c017f[collection1] main]
[junit4] 2> 1732771 WARN (coreLoadExecutor-6983-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 1732771 INFO (coreLoadExecutor-6983-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1732772 INFO (searcherExecutor-6984-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2d1c017f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1732772 INFO (coreLoadExecutor-6983-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1549289056572014592
[junit4] 2> 1732775 INFO (SUITE-TestJsonFacets-seed#[219BCB0F004F08C3]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1732778 INFO (TEST-TestJsonFacets.testErrors-seed#[219BCB0F004F08C3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testErrors {p0=DV}
[junit4] 2> 1732780 INFO (TEST-TestJsonFacets.testErrors-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:* (-1549289056579354624)} 0 1
[junit4] 2> 1732781 INFO (TEST-TestJsonFacets.testErrors-seed#[219BCB0F004F08C3]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching ignore_exception
[junit4] 2> 1732781 INFO (TEST-TestJsonFacets.testErrors-seed#[219BCB0F004F08C3]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={ignore_exception=true&q=*:*&json.facet={f:{type:ignore_exception_aaa,+field:bbbbbb}}&wt=json&indent=true} status=400 QTime=0
[junit4] 2> 1732782 INFO (TEST-TestJsonFacets.testErrors-seed#[219BCB0F004F08C3]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testErrors {p0=DV}
[junit4] 2> 1732784 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMethodStream {p0=DV}
[junit4] 2> 1732785 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:* (-1549289056585646080)} 0 0
[junit4] 2> 1732785 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1 (1549289056585646081)]} 0 0
[junit4] 2> 1732786 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2 (1549289056586694656)]} 0 0
[junit4] 2> 1732787 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[3 (1549289056586694657)]} 0 0
[junit4] 2> 1732787 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1732787 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@69f84b50
[junit4] 2> 1732789 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6cbb579c[collection1] main]
[junit4] 2> 1732789 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1732790 INFO (searcherExecutor-6984-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6cbb579c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C3)))}
[junit4] 2> 1732790 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 3
[junit4] 2> 1732791 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4 (1549289056591937536)]} 0 0
[junit4] 2> 1732791 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5 (1549289056591937537)]} 0 0
[junit4] 2> 1732792 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1732792 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@69f84b50
[junit4] 2> 1732793 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@743f81aa[collection1] main]
[junit4] 2> 1732793 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1732794 INFO (searcherExecutor-6984-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@743f81aa[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C3) Uninverting(_1(6.3.0):C2)))}
[junit4] 2> 1732794 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 2
[junit4] 2> 1732796 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6 (1549289056596131840)]} 0 1
[junit4] 2> 1732796 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1732796 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@69f84b50
[junit4] 2> 1732799 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@43d910f5[collection1] main]
[junit4] 2> 1732799 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1732800 INFO (searcherExecutor-6984-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@43d910f5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C3) Uninverting(_1(6.3.0):C2) Uninverting(_2(6.3.0):C1)))}
[junit4] 2> 1732800 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 4
[junit4] 2> 1732802 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&json.facet={x:'sum(num_d)'}&json.facet={y:'min(num_d)'}&rows=0&wt=xml} hits=6 status=0 QTime=1
[junit4] 2> 1732806 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&json.facet={+++cat:{terms:{field:'cat_s',+method:stream+}},+cat2:{terms:{field:'cat_s',+method:stream,+sort:'index+asc'+}},+cat3:{terms:{field:'cat_s',+method:stream,+sort:'index+asc',+mincount:3+}},+cat4:{terms:{field:'cat_s',+method:stream,+sort:'index+asc',+prefix:B+}},+cat5:{terms:{field:'cat_s',+method:stream,+sort:'index+asc',+offset:1+}}+}&rows=0&wt=xml} hits=6 status=0 QTime=3
[junit4] 2> 1732807 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&json.facet={+++cat:{terms:{field:'cat_s',+sort:'index+asc',+facet:{where:{terms:{field:where_s,method:stream,sort:'index+asc'}}}+++}}}&rows=0&wt=xml} hits=6 status=0 QTime=0
[junit4] 2> 1732808 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&json.facet={+++cat:{terms:{field:'cat_s',+method:stream,sort:'index+asc',+facet:{where:{terms:{field:where_s,method:stream,sort:'index+asc'}}}+++}}}&rows=0&wt=xml} hits=6 status=0 QTime=0
[junit4] 2> 1732808 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&json.facet={+++cat:{terms:{field:'cat_s',+method:stream,sort:'index+asc',+facet:{++where:{terms:{field:where_s,method:stream,sort:'index+asc',sort:'index+asc',+facet:{x:'max(num_d)'}+++++}}}+++}}}&rows=0&wt=xml} hits=6 status=0 QTime=0
[junit4] 2> 1732809 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&json.facet={+++cat:{terms:{field:'cat_s',+method:stream,sort:'index+asc',+facet:{+y:'min(num_d)',++where:{terms:{field:where_s,method:stream,sort:'index+asc',+facet:{x:'max(num_d)'}+++++}}}+++}}}&rows=0&facet=true&wt=xml} hits=6 status=0 QTime=0
[junit4] 2> 1732810 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fq=cat_s:A&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 1732810 INFO (TEST-TestJsonFacets.testMethodStream-seed#[219BCB0F004F08C3]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMethodStream {p0=DV}
[junit4] 2> 1732818 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBigger {p0=DV}
[junit4] 2> 1732824 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1732830 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1b0b3354{/solr,null,AVAILABLE}
[junit4] 2> 1732830 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.e.j.s.ServerConnector Started ServerConnector@49716b08{HTTP/1.1,[http/1.1]}{127.0.0.1:56654}
[junit4] 2> 1732830 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.e.j.s.Server Started @1735113ms
[junit4] 2> 1732830 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema_latest.xml, solrconfig=solrconfig-tlog.xml, hostContext=/solr, hostPort=56654}
[junit4] 2> 1732830 ERROR (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1732830 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.3.0
[junit4] 2> 1732830 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1732830 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1732830 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-26T21:26:22Z
[junit4] 2> 1732831 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/s0-001/solr.xml
[junit4] 2> 1732835 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1732837 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/s0-001/.
[junit4] 2> 1732837 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1732850 INFO (coreLoadExecutor-6994-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1732860 INFO (coreLoadExecutor-6994-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=example
[junit4] 2> 1732908 INFO (coreLoadExecutor-6994-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
[junit4] 2> 1732912 INFO (coreLoadExecutor-6994-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/s0-001/./collection1
[junit4] 2> 1732912 INFO (coreLoadExecutor-6994-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/s0-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/s0-001/./collection1/data/]
[junit4] 2> 1732912 INFO (coreLoadExecutor-6994-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5410ef4f
[junit4] 2> 1732913 INFO (coreLoadExecutor-6994-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=6.078125, floorSegmentMB=2.1630859375, forceMergeDeletesPctAllowed=11.645507500074006, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.37405726359010283
[junit4] 2> 1732916 WARN (coreLoadExecutor-6994-thread-1) [ x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 1732922 INFO (coreLoadExecutor-6994-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1732922 INFO (coreLoadExecutor-6994-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1732922 INFO (coreLoadExecutor-6994-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1732922 INFO (coreLoadExecutor-6994-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1732922 INFO (coreLoadExecutor-6994-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8158854530582518]
[junit4] 2> 1732923 INFO (coreLoadExecutor-6994-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@66fca85[collection1] main]
[junit4] 2> 1732923 INFO (coreLoadExecutor-6994-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/s0-001/collection1/conf
[junit4] 2> 1732923 INFO (coreLoadExecutor-6994-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1732924 INFO (searcherExecutor-6995-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@66fca85[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1732924 INFO (coreLoadExecutor-6994-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1549289056731398144
[junit4] 2> 1733343 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.SolrTestCaseHS ===> Started solr server port=56654 home=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/s0-001
[junit4] 2> 1733352 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1733354 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4a15aef3{/solr,null,AVAILABLE}
[junit4] 2> 1733354 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.e.j.s.ServerConnector Started ServerConnector@3fa1264b{HTTP/1.1,[http/1.1]}{127.0.0.1:44049}
[junit4] 2> 1733355 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.e.j.s.Server Started @1735638ms
[junit4] 2> 1733355 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema_latest.xml, solrconfig=solrconfig-tlog.xml, hostContext=/solr, hostPort=44049}
[junit4] 2> 1733355 ERROR (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1733355 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.3.0
[junit4] 2> 1733355 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1733355 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1733355 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-26T21:26:22.525Z
[junit4] 2> 1733356 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/s1-001/solr.xml
[junit4] 2> 1733359 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1733361 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/s1-001/.
[junit4] 2> 1733361 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1733371 INFO (coreLoadExecutor-7005-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1733386 INFO (coreLoadExecutor-7005-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=example
[junit4] 2> 1733439 INFO (coreLoadExecutor-7005-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
[junit4] 2> 1733444 INFO (coreLoadExecutor-7005-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/s1-001/./collection1
[junit4] 2> 1733444 INFO (coreLoadExecutor-7005-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/s1-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/s1-001/./collection1/data/]
[junit4] 2> 1733444 INFO (coreLoadExecutor-7005-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5410ef4f
[junit4] 2> 1733445 INFO (coreLoadExecutor-7005-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=6.078125, floorSegmentMB=2.1630859375, forceMergeDeletesPctAllowed=11.645507500074006, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.37405726359010283
[junit4] 2> 1733448 WARN (coreLoadExecutor-7005-thread-1) [ x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 1733453 INFO (coreLoadExecutor-7005-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1733453 INFO (coreLoadExecutor-7005-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1733454 INFO (coreLoadExecutor-7005-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1733454 INFO (coreLoadExecutor-7005-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1733455 INFO (coreLoadExecutor-7005-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8158854530582518]
[junit4] 2> 1733455 INFO (coreLoadExecutor-7005-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@11ed741d[collection1] main]
[junit4] 2> 1733455 INFO (coreLoadExecutor-7005-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/s1-001/collection1/conf
[junit4] 2> 1733456 INFO (coreLoadExecutor-7005-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1733456 INFO (coreLoadExecutor-7005-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1549289057289240576
[junit4] 2> 1733458 INFO (searcherExecutor-7006-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@11ed741d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1733861 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.SolrTestCaseHS ===> Started solr server port=44049 home=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/s1-001
[junit4] 2> 1733870 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1733871 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@692f5de7{/solr,null,AVAILABLE}
[junit4] 2> 1733871 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1d507559{HTTP/1.1,[http/1.1]}{127.0.0.1:53158}
[junit4] 2> 1733872 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.e.j.s.Server Started @1736154ms
[junit4] 2> 1733872 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema_latest.xml, solrconfig=solrconfig-tlog.xml, hostContext=/solr, hostPort=53158}
[junit4] 2> 1733872 ERROR (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1733873 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.3.0
[junit4] 2> 1733873 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1733873 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1733873 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-26T21:26:23.043Z
[junit4] 2> 1733873 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/s2-001/solr.xml
[junit4] 2> 1733877 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1733879 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/s2-001/.
[junit4] 2> 1733879 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1733886 INFO (coreLoadExecutor-7016-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1733896 INFO (coreLoadExecutor-7016-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=example
[junit4] 2> 1733961 INFO (coreLoadExecutor-7016-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
[junit4] 2> 1733966 INFO (coreLoadExecutor-7016-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/s2-001/./collection1
[junit4] 2> 1733966 INFO (coreLoadExecutor-7016-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/s2-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/s2-001/./collection1/data/]
[junit4] 2> 1733966 INFO (coreLoadExecutor-7016-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5410ef4f
[junit4] 2> 1733967 INFO (coreLoadExecutor-7016-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=6.078125, floorSegmentMB=2.1630859375, forceMergeDeletesPctAllowed=11.645507500074006, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.37405726359010283
[junit4] 2> 1733970 WARN (coreLoadExecutor-7016-thread-1) [ x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 1733975 INFO (coreLoadExecutor-7016-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1733975 INFO (coreLoadExecutor-7016-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1733976 INFO (coreLoadExecutor-7016-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1733976 INFO (coreLoadExecutor-7016-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1733977 INFO (coreLoadExecutor-7016-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8158854530582518]
[junit4] 2> 1733977 INFO (coreLoadExecutor-7016-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@44a320e7[collection1] main]
[junit4] 2> 1733977 INFO (coreLoadExecutor-7016-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/s2-001/collection1/conf
[junit4] 2> 1733978 INFO (coreLoadExecutor-7016-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1733979 INFO (searcherExecutor-7017-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@44a320e7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1733979 INFO (coreLoadExecutor-7016-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1549289057837645824
[junit4] 2> 1734381 INFO (TEST-TestJsonFacets.testBigger-seed#[219BCB0F004F08C3]) [ ] o.a.s.SolrTestCaseHS ===> Started solr server port=53158 home=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001/s2-001
[junit4] 2> 1734389 INFO (qtp1777190498-19758) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1549289058265464832)} 0 2
[junit4] 2> 1734392 INFO (qtp492578613-19775) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1549289058269659136)} 0 1
[junit4] 2> 1734395 INFO (qtp1378605950-19792) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1549289058271756288)} 0 1
[junit4] 2> 1734397 INFO (qtp492578613-19776) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0 (1549289058275950592)]} 0 0
[junit4] 2> 1734399 INFO (qtp1378605950-19793) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1 (1549289058276999168)]} 0 0
[junit4] 2> 1734401 INFO (qtp1777190498-19759) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2 (1549289058280144896)]} 0 0
[junit4] 2> 1734403 INFO (qtp492578613-19777) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3 (1549289058282242048)]} 0 0
[junit4] 2> 1734404 INFO (qtp1378605950-19794) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4 (1549289058283290624)]} 0 0
[junit4] 2> 1734406 INFO (qtp1777190498-19760) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5 (1549289058285387776)]} 0 0
[junit4] 2> 1734408 INFO (qtp492578613-19778) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6 (1549289058287484928)]} 0 0
[junit4] 2> 1734412 INFO (qtp1378605950-19795) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7 (1549289058291679232)]} 0 0
[junit4] 2> 1734414 INFO (qtp1378605950-19796) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8 (1549289058292727808)]} 0 0
[junit4] 2> 1734415 INFO (qtp1777190498-19761) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1734415 INFO (qtp1777190498-19761) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@22f7fbab
[junit4] 2> 1734418 INFO (qtp1777190498-19761) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@78bb97e6[collection1] main]
[junit4] 2> 1734418 INFO (qtp1777190498-19761) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1734419 INFO (searcherExecutor-6995-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@78bb97e6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C2)))}
[junit4] 2> 1734419 INFO (qtp1777190498-19761) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 4
[junit4] 2> 1734421 INFO (qtp492578613-19779) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1734421 INFO (qtp492578613-19779) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7ae40c84
[junit4] 2> 1734423 INFO (qtp492578613-19779) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@729f7c8e[collection1] main]
[junit4] 2> 1734423 INFO (qtp492578613-19779) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1734424 INFO (searcherExecutor-7006-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@729f7c8e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C3)))}
[junit4] 2> 1734424 INFO (qtp492578613-19779) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
[junit4] 2> 1734426 INFO (qtp1378605950-19789) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1734426 INFO (qtp1378605950-19789) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5cdba005
[junit4] 2> 1734431 INFO (qtp1378605950-19789) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a03817c[collection1] main]
[junit4] 2> 1734431 INFO (qtp1378605950-19789) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1734432 INFO (searcherExecutor-7017-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1a03817c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C4)))}
[junit4] 2> 1734432 INFO (qtp1378605950-19789) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 6
[junit4] 2> 1734435 INFO (qtp1777190498-19762) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9 (1549289058314747904)]} 0 1
[junit4] 2> 1734438 INFO (qtp1378605950-19792) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10 (1549289058317893632)]} 0 1
[junit4] 2> 1734441 INFO (qtp492578613-19772) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11 (1549289058321039360)]} 0 1
[junit4] 2> 1734442 INFO (qtp1378605950-19793) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12 (1549289058323136512)]} 0 0
[junit4] 2> 1734444 INFO (qtp1777190498-19755) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[13 (1549289058325233664)]} 0 0
[junit4] 2> 1734446 INFO (qtp492578613-19775) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[14 (1549289058327330816)]} 0 0
[junit4] 2> 1734448 INFO (qtp1378605950-19793) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[15 (1549289058329427968)]} 0 0
[junit4] 2> 1734449 INFO (qtp1777190498-19755) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16 (1549289058330476544)]} 0 0
[junit4] 2> 1734451 INFO (qtp492578613-19776) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17 (1549289058332573696)]} 0 0
[junit4] 2> 1734453 INFO (qtp1777190498-19755) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1734453 INFO (qtp1777190498-19755) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@22f7fbab
[junit4] 2> 1734456 INFO (qtp1777190498-19755) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5d068d2b[collection1] main]
[junit4] 2> 1734456 INFO (qtp1777190498-19755) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1734457 INFO (searcherExecutor-6995-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5d068d2b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C2) Uninverting(_1(6.3.0):C3)))}
[junit4] 2> 1734457 INFO (qtp1777190498-19755) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 4
[junit4] 2> 1734459 INFO (qtp492578613-19777) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1734459 INFO (qtp492578613-19777) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7ae40c84
[junit4] 2> 1734462 INFO (qtp492578613-19777) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@52e8491c[collection1] main]
[junit4] 2> 1734462 INFO (qtp492578613-19777) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1734463 INFO (searcherExecutor-7006-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@52e8491c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C3) Uninverting(_1(6.3.0):C3)))}
[junit4] 2> 1734463 INFO (qtp492578613-19777) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 1734465 INFO (qtp1378605950-19793) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1734465 INFO (qtp1378605950-19793) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5cdba005
[junit4] 2> 1734467 INFO (qtp1378605950-19793) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3d24d984[collection1] main]
[junit4] 2> 1734467 INFO (qtp1378605950-19793) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1734468 INFO (searcherExecutor-7017-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3d24d984[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C4) Uninverting(_1(6.3.0):C3)))}
[junit4] 2> 1734468 INFO (qtp1378605950-19793) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 1734470 INFO (qtp1378605950-19796) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[18 (1549289058351448064)]} 0 1
[junit4] 2> 1734472 INFO (qtp1378605950-19789) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[19 (1549289058354593792)]} 0 0
[junit4] 2> 1734474 INFO (qtp1777190498-19760) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20 (1549289058356690944)]} 0 0
[junit4] 2> 1734477 INFO (qtp492578613-19778) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[21 (1549289058358788096)]} 0 0
[junit4] 2> 1734479 INFO (qtp492578613-19779) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[22 (1549289058361933824)]} 0 0
[junit4] 2> 1734481 INFO (qtp1378605950-19792) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[23 (1549289058364030976)]} 0 0
[junit4] 2> 1734482 INFO (qtp1777190498-19761) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[24 (1549289058365079552)]} 0 0
[junit4] 2> 1734484 INFO (qtp492578613-19772) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[25 (1549289058367176704)]} 0 0
[junit4] 2> 1734486 INFO (qtp1378605950-19794) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[26 (1549289058369273856)]} 0 0
[junit4] 2> 1734488 INFO (qtp1777190498-19762) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[27 (1549289058371371008)]} 0 0
[junit4] 2> 1734490 INFO (qtp492578613-19775) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[28 (1549289058373468160)]} 0 0
[junit4] 2> 1734492 INFO (qtp1777190498-19758) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1734492 INFO (qtp1777190498-19758) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@22f7fbab
[junit4] 2> 1734494 INFO (qtp1777190498-19758) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5cb001e1[collection1] main]
[junit4] 2> 1734495 INFO (qtp1777190498-19758) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1734496 INFO (searcherExecutor-6995-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5cb001e1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C2) Uninverting(_1(6.3.0):C3) Uninverting(_2(6.3.0):C3)))}
[junit4] 2> 1734496 INFO (qtp1777190498-19758) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 4
[junit4] 2> 1734498 INFO (qtp492578613-19776) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1734498 INFO (qtp492578613-19776) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7ae40c84
[junit4] 2> 1734500 INFO (qtp492578613-19776) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7bffef14[collection1] main]
[junit4] 2> 1734500 INFO (qtp492578613-19776) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1734501 INFO (searcherExecutor-7006-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7bffef14[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C3) Uninverting(_1(6.3.0):C3) Uninverting(_2(6.3.0):C4)))}
[junit4] 2> 1734501 INFO (qtp492578613-19776) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 1734503 INFO (qtp1378605950-19795) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1734503 INFO (qtp1378605950-19795) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5cdba005
[junit4] 2> 1734505 INFO (qtp1378605950-19795) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@66ce9ca4[collection1] main]
[junit4] 2> 1734505 INFO (qtp1378605950-19795) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1734506 INFO (searcherExecutor-7017-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@66ce9ca4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C4) Uninverting(_1(6.3.0):C3) Uninverting(_2(6.3.0):C4)))}
[junit4] 2> 1734506 INFO (qtp1378605950-19795) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 1734508 INFO (qtp1378605950-19793) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[29 (1549289058391293952)]} 0 0
[junit4] 2> 1734510 INFO (qtp1378605950-19796) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[30 (1549289058393391104)]} 0 0
[junit4] 2> 1734512 INFO (qtp1777190498-19759) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[31 (1549289058395488256)]} 0 0
[junit4] 2> 1734514 INFO (qtp492578613-19777) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[32 (1549289058398633984)]} 0 0
[junit4] 2> 1734516 INFO (qtp1777190498-19759) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[33 (1549289058400731136)]} 0 0
[junit4] 2> 1734518 INFO (qtp492578613-19778) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[34 (1549289058402828288)]} 0 0
[junit4] 2> 1734520 INFO (qtp1378605950-19789) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[35 (1549289058404925440)]} 0 0
[junit4] 2> 1734522 INFO (qtp1777190498-19760) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[36 (1549289058407022592)]} 0 0
[junit4] 2> 1734523 INFO (qtp492578613-19778) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[37 (1549289058408071168)]} 0 0
[junit4] 2> 1734525 INFO (qtp1378605950-19792) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[38 (1549289058410168320)]} 0 0
[junit4] 2> 1734527 INFO (qtp1777190498-19761) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[39 (1549289058412265472)]} 0 0
[junit4] 2> 1734529 INFO (qtp1777190498-19762) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[40 (1549289058414362624)]} 0 0
[junit4] 2> 1734531 INFO (qtp1777190498-19758) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1734531 INFO (qtp1777190498-19758) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@22f7fbab
[junit4] 2> 1734532 INFO (qtp1777190498-19758) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c7c3882[collection1] main]
[junit4] 2> 1734533 INFO (searcherExecutor-6995-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c7c3882[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C2) Uninverting(_1(6.3.0):C3) Uninverting(_2(6.3.0):C3) Uninverting(_3(6.3.0):C5)))}
[junit4] 2> 1734534 INFO (qtp1777190498-19758) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1734534 INFO (qtp1777190498-19758) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
[junit4] 2> 1734535 INFO (qtp492578613-19772) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1734535 INFO (qtp492578613-19772) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7ae40c84
[junit4] 2> 1734537 INFO (qtp492578613-19772) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4b702208[collection1] main]
[junit4] 2> 1734538 INFO (qtp492578613-19772) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1734538 INFO (searcherExecutor-7006-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4b702208[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C3) Uninverting(_1(6.3.0):C3) Uninverting(_2(6.3.0):C4) Uninverting(_3(6.3.0):C3)))}
[junit4] 2> 1734539 INFO (qtp492578613-19772) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 1734540 INFO (qtp1378605950-19792) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1734540 INFO (qtp1378605950-19792) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5cdba005
[junit4] 2> 1734542 INFO (qtp1378605950-19792) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5ace4893[collection1] main]
[junit4] 2> 1734542 INFO (qtp1378605950-19792) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1734543 INFO (searcherExecutor-7017-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5ace4893[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C4) Uninverting(_1(6.3.0):C3) Uninverting(_2(6.3.0):C4) Uninverting(_3(6.3.0):C4)))}
[junit4] 2> 1734543 INFO (qtp1378605950-19792) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&so
[...truncated too long message...]
down CoreContainer instance=1378044915
[junit4] 2> 1760857 INFO (coreCloseExecutor-7024-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@a04d256
[junit4] 2> 1760863 INFO (SUITE-TestJsonFacets-seed#[219BCB0F004F08C3]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4a15aef3{/solr,null,UNAVAILABLE}
[junit4] 2> 1760864 INFO (SUITE-TestJsonFacets-seed#[219BCB0F004F08C3]-worker) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@1d507559{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1760864 INFO (SUITE-TestJsonFacets-seed#[219BCB0F004F08C3]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1512902649
[junit4] 2> 1760865 INFO (coreCloseExecutor-7026-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5dd20976
[junit4] 2> 1761262 INFO (SUITE-TestJsonFacets-seed#[219BCB0F004F08C3]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@692f5de7{/solr,null,UNAVAILABLE}
[junit4] 2> 1761263 INFO (SUITE-TestJsonFacets-seed#[219BCB0F004F08C3]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1761263 INFO (SUITE-TestJsonFacets-seed#[219BCB0F004F08C3]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=434430780
[junit4] 2> 1761270 INFO (coreCloseExecutor-7028-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3eb89483
[junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_219BCB0F004F08C3-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {num_dd=PostingsFormat(name=LuceneFixedGap), super_s=FSTOrd50, num_lds=PostingsFormat(name=Direct), date_dt=FSTOrd50, cat_sds=FSTOrd50, where_sds=PostingsFormat(name=Direct), Z_num_id=PostingsFormat(name=LuceneFixedGap), super_sds=PostingsFormat(name=Direct), cat_ss=PostingsFormat(name=LuceneFixedGap), sparse_ss=FSTOrd50, book_s=PostingsFormat(name=LuceneFixedGap), super_sd=PostingsFormat(name=LuceneFixedGap), where_s=PostingsFormat(name=LuceneFixedGap), Z_num_i=PostingsFormat(name=LuceneFixedGap), sparse_sd=Lucene50(blocksize=128), id=PostingsFormat(name=LuceneFixedGap), num_is=FSTOrd50, Z_num_l=PostingsFormat(name=Direct), multi_ss=PostingsFormat(name=Direct), type_s=PostingsFormat(name=Direct), date_dtd=PostingsFormat(name=LuceneFixedGap), sparse_sds=PostingsFormat(name=LuceneFixedGap), page_s=FSTOrd50, cat_sd=PostingsFormat(name=Direct), super_ss=Lucene50(blocksize=128), val_b=Lucene50(blocksize=128), _root_=PostingsFormat(name=Direct), multi_sds=Lucene50(blocksize=128), num_f=FSTOrd50, cat_s=PostingsFormat(name=Direct), v_t=PostingsFormat(name=LuceneFixedGap), where_sd=FSTOrd50, make_s=PostingsFormat(name=Direct), num_id=Lucene50(blocksize=128), num_d=PostingsFormat(name=LuceneFixedGap), num_ds=Lucene50(blocksize=128), date_dtds=Lucene50(blocksize=128), num_i=Lucene50(blocksize=128), cost_f=Lucene50(blocksize=128), sparse_s=Lucene50(blocksize=128), num_l=PostingsFormat(name=LuceneFixedGap), num_ls=Lucene50(blocksize=128), where_ss=Lucene50(blocksize=128), num_dds=PostingsFormat(name=Direct), model_s=FSTOrd50, num_fds=Lucene50(blocksize=128), num_fs=PostingsFormat(name=Direct), num_ids=FSTOrd50}, docValues:{num_dd=DocValuesFormat(name=Asserting), num_lds=DocValuesFormat(name=Direct), date_dtd=DocValuesFormat(name=Asserting), sparse_sds=DocValuesFormat(name=Asserting), cat_sd=DocValuesFormat(name=Direct), cat_sds=DocValuesFormat(name=Lucene54), multi_sds=DocValuesFormat(name=Memory), where_sds=DocValuesFormat(name=Direct), where_sd=DocValuesFormat(name=Lucene54), Z_num_id=DocValuesFormat(name=Asserting), num_id=DocValuesFormat(name=Memory), super_sds=DocValuesFormat(name=Direct), date_dtds=DocValuesFormat(name=Memory), _version_=DocValuesFormat(name=Direct), cat_ss=DocValuesFormat(name=Asserting), super_sd=DocValuesFormat(name=Asserting), sparse_sd=DocValuesFormat(name=Memory), id=DocValuesFormat(name=Asserting), where_ss=DocValuesFormat(name=Memory), num_dds=DocValuesFormat(name=Direct), num_fds=DocValuesFormat(name=Memory), num_ids=DocValuesFormat(name=Lucene54)}, maxPointsInLeafNode=1866, maxMBSortInHeap=6.123514544556671, sim=ClassicSimilarity, locale=pt-BR, timezone=America/Kralendijk
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=4,threads=1,free=402967856,total=528482304
[junit4] 2> NOTE: All tests run in this JVM: [DistributedDebugComponentTest, SuggesterTSTTest, TestScoreJoinQPScore, BinaryUpdateRequestHandlerTest, FullSolrCloudDistribCmdsTest, TestRestoreCore, AutoCommitTest, TestSchemaVersionResource, HLLUtilTest, BlockJoinFacetSimpleTest, TestNamedUpdateProcessors, TestCollationField, DistributedQueueTest, SpellPossibilityIteratorTest, TestReversedWildcardFilterFactory, TestConfig, HdfsChaosMonkeyNothingIsSafeTest, TestCollapseQParserPlugin, MinimalSchemaTest, RequestHandlersTest, TestMergePolicyConfig, BadComponentTest, TestCloudInspectUtil, TestExceedMaxTermLength, RollingRestartTest, XmlUpdateRequestHandlerTest, SolrCoreCheckLockOnStartupTest, CdcrBootstrapTest, StressHdfsTest, SolrXmlInZkTest, TestJmxMonitoredMap, BJQParserTest, TermVectorComponentDistributedTest, VMParamsZkACLAndCredentialsProvidersTest, TestExtendedDismaxParser, TestBlendedInfixSuggestions, TestMinMaxOnMultiValuedField, DistributedFacetPivotSmallTest, TestUseDocValuesAsStored2, CircularListTest, PluginInfoTest, SimpleMLTQParserTest, ReplaceNodeTest, BasicAuthIntegrationTest, TestAnalyzedSuggestions, NoCacheHeaderTest, TestManagedSynonymFilterFactory, TestLeaderInitiatedRecoveryThread, TestSizeLimitedDistributedMap, SolrPluginUtilsTest, DistributedQueryComponentOptimizationTest, TestHashQParserPlugin, TestRecovery, TimeZoneUtilsTest, UnloadDistributedZkTest, HdfsRestartWhileUpdatingTest, CursorMarkTest, CollectionStateFormat2Test, StatelessScriptUpdateProcessorFactoryTest, BigEndianAscendingWordDeserializerTest, TestConfigSetProperties, HttpPartitionTest, TestSolrXml, TestShardHandlerFactory, TestPhraseSuggestions, TestSchemaNameResource, TestPKIAuthenticationPlugin, HardAutoCommitTest, ResponseLogComponentTest, BasicDistributedZk2Test, TestXmlQParserPlugin, FieldMutatingUpdateProcessorTest, ConnectionReuseTest, SOLR749Test, CoreAdminRequestStatusTest, ConnectionManagerTest, TestConfigSetsAPIExclusivity, TestMissingGroups, TestFieldSortValues, PrimitiveFieldTypeTest, TestStressCloudBlindAtomicUpdates, OverriddenZkACLAndCredentialsProvidersTest, TestSearcherReuse, TestSolrDeletionPolicy1, DOMUtilTest, DirectUpdateHandlerTest, TestBackupRepositoryFactory, SecurityConfHandlerTest, ZkStateWriterTest, CurrencyFieldXmlFileTest, TestSolrQueryParserResource, TestSubQueryTransformerCrossCore, DataDrivenBlockJoinTest, ParsingFieldUpdateProcessorsTest, ExplicitHLLTest, TestStressReorder, AddSchemaFieldsUpdateProcessorFactoryTest, TestHighFrequencyDictionaryFactory, LukeRequestHandlerTest, FieldAnalysisRequestHandlerTest, TestManagedResourceStorage, TestBinaryField, TestXIncludeConfig, TestCloudRecovery, SyncSliceTest, BasicZkTest, RecoveryZkTest, LeaderElectionTest, TestZkChroot, ShardRoutingCustomTest, TestRealTimeGet, TestStressVersions, TestCoreContainer, SimpleFacetsTest, TestGroupingSearch, BadIndexSchemaTest, TestFiltering, SolrIndexSplitterTest, TestCoreDiscovery, CoreAdminHandlerTest, SuggesterWFSTTest, WordBreakSolrSpellCheckerTest, TestWordDelimiterFilterFactory, DirectUpdateHandlerOptimizeTest, SolrInfoMBeanTest, DebugComponentTest, TestQueryUtils, TestWriterPerf, TermsComponentTest, TermVectorComponentTest, TestSolrQueryParser, ReturnFieldsTest, TestCSVResponseWriter, PingRequestHandlerTest, TestQuerySenderListener, UpdateParamsTest, AlternateDirectoryTest, SolrIndexConfigTest, ExternalFileFieldSortTest, TestSolrCoreProperties, SynonymTokenizerTest, TestSweetSpotSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestFastOutputStream, OpenExchangeRatesOrgProviderTest, TestFastLRUCache, PreAnalyzedFieldTest, PrimUtilsTest, TestSolrJ, TestUtils, ZkNodePropsTest, TestRTGBase, DistributedIntervalFacetingTest, CollectionsAPIAsyncDistributedZkTest, DeleteInactiveReplicaTest, DeleteLastCustomShardedReplicaTest, DistribCursorPagingTest, LeaderElectionContextKeyTest, LeaderFailoverAfterPartitionTest, MultiThreadedOCPTest, OverseerStatusTest, ReplicationFactorTest, RestartWhileUpdatingTest, SSLMigrationTest, SaslZkACLProviderTest, SharedFSAutoReplicaFailoverUtilsTest, SimpleCollectionCreateDeleteTest, TestAuthenticationFramework, TestCloudPivotFacet, TestCollectionAPI, TestExclusionRuleCollectionAccess, TestLeaderElectionWithEmptyReplica, TestLeaderElectionZkExpiry, TestLocalFSCloudBackupRestore, TestOnReconnectListenerSupport, TestRebalanceLeaders, TestRequestStatusCollectionAPI, TestTolerantUpdateProcessorRandomCloud, HdfsThreadLeakTest, HdfsTlogReplayBufferedWhileIndexingTest, ZkStateReaderTest, RuleEngineTest, RulesTest, TestCustomStream, TestSolrCoreSnapshots, RequestLoggingTest, TestBlobHandler, TestSQLHandler, CoreAdminCreateDiscoverTest, CoreMergeIndexesAdminHandlerTest, SegmentsInfoRequestHandlerTest, DistributedFacetPivotLargeTest, DistributedFacetPivotSmallAdvancedTest, DistributedSuggestComponentTest, TestPivotHelperCode, TestTrackingShardHandlerFactory, TestSlowCompositeReaderWrapper, CheckHdfsIndexTest, TestCollationFieldDocValues, TestManagedSchemaThreadSafety, TestSchemaManager, TestUseDocValuesAsStored, AnalyticsMergeStrategyTest, AnalyticsQueryTest, MergeStrategyTest, TestReRankQParserPlugin, TestSolr4Spatial2, TestSolrFieldCacheMBean, TestStandardQParsers, TestStressUserVersions, TestTrieFacet, TestJsonFacetRefinement, TestJsonFacets]
[junit4] Completed [610/645 (1!)] on J2 in 28.73s, 48 tests, 6 errors <<< FAILURES!
[...truncated 60467 lines...]