You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/10/18 05:35:13 UTC
[JENKINS] Lucene-Solr-6.x-Linux (64bit/jdk1.8.0_102) - Build # 1976
- Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/1976/
Java: 64bit/jdk1.8.0_102 -XX:+UseCompressedOops -XX:+UseParallelGC
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, SolrCore, MockDirectoryWrapper] 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:328) at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:635) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:843) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:769) 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:667) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:843) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:769) 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:792) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:769) 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:933) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:769) 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:430) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:837) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:769) 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, SolrCore, MockDirectoryWrapper]
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:328)
at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:635)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:843)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:769)
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:667)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:843)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:769)
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:792)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:769)
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:933)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:769)
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:430)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:837)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:769)
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([534FFA4EC31DFBC3]: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.GeneratedMethodAccessor27.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:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
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=8213, name=searcherExecutor-4567-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=8213, name=searcherExecutor-4567-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([534FFA4EC31DFBC3]: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=8213, name=searcherExecutor-4567-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=8213, name=searcherExecutor-4567-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([534FFA4EC31DFBC3]:0)
Build Log:
[...truncated 12002 lines...]
[junit4] Suite: org.apache.solr.core.TestLazyCores
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/init-core-data-001
[junit4] 2> 1039973 INFO (SUITE-TestLazyCores-seed#[534FFA4EC31DFBC3]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1039975 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBadConfigsGenerateErrors
[junit4] 2> 1039987 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 1039990 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ ] o.a.s.c.CorePropertiesLocator Found 6 core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001
[junit4] 2> 1039990 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [core2, badConfig2, core1, badSchema1, badConfig1, badSchema2]
[junit4] 2> 1039999 INFO (coreLoadExecutor-4451-thread-2) [ ] o.a.s.c.Config Ignoring exception matching \QSAXParseException\E
[junit4] 2> 1039999 ERROR (coreLoadExecutor-4451-thread-2) [ ] o.a.s.c.CoreContainer Error creating core [badConfig2]: Could not load conf for core badConfig2: Error loading solr config from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badConfig2/conf/solrconfig.xml
[junit4] 2> org.apache.solr.common.SolrException: Could not load conf for core badConfig2: Error loading solr config from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badConfig2/conf/solrconfig.xml
[junit4] 2> at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:85)
[junit4] 2> at org.apache.solr.core.CoreContainer.create(CoreContainer.java:840)
[junit4] 2> at org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Error loading solr config from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badConfig2/conf/solrconfig.xml
[junit4] 2> at org.apache.solr.core.SolrConfig.readFromResourceLoader(SolrConfig.java:187)
[junit4] 2> at org.apache.solr.core.ConfigSetService.createSolrConfig(ConfigSetService.java:97)
[junit4] 2> at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:77)
[junit4] 2> ... 7 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: org.xml.sax.SAXParseException; systemId: solrres:/solrconfig.xml; lineNumber: 56; columnNumber: 5; The element type "reqsthalr" must be terminated by the matching end-tag "</reqsthalr>".
[junit4] 2> at org.apache.solr.core.Config.<init>(Config.java:154)
[junit4] 2> at org.apache.solr.core.Config.<init>(Config.java:90)
[junit4] 2> at org.apache.solr.core.SolrConfig.<init>(SolrConfig.java:202)
[junit4] 2> at org.apache.solr.core.SolrConfig.readFromResourceLoader(SolrConfig.java:179)
[junit4] 2> ... 9 more
[junit4] 2> Caused by: org.xml.sax.SAXParseException; systemId: solrres:/solrconfig.xml; lineNumber: 56; columnNumber: 5; The element type "reqsthalr" must be terminated by the matching end-tag "</reqsthalr>".
[junit4] 2> at com.sun.org.apache.xerces.internal.util.ErrorHandlerWrapper.createSAXParseException(ErrorHandlerWrapper.java:203)
[junit4] 2> at com.sun.org.apache.xerces.internal.util.ErrorHandlerWrapper.fatalError(ErrorHandlerWrapper.java:177)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLErrorReporter.reportError(XMLErrorReporter.java:400)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLErrorReporter.reportError(XMLErrorReporter.java:327)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLScanner.reportFatalError(XMLScanner.java:1472)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanEndElement(XMLDocumentFragmentScannerImpl.java:1749)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl$FragmentContentDriver.next(XMLDocumentFragmentScannerImpl.java:2967)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(XMLDocumentScannerImpl.java:602)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.next(XMLNSDocumentScannerImpl.java:112)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanDocument(XMLDocumentFragmentScannerImpl.java:505)
[junit4] 2> at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(XML11Configuration.java:841)
[junit4] 2> at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(XML11Configuration.java:770)
[junit4] 2> at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(XMLParser.java:141)
[junit4] 2> at com.sun.org.apache.xerces.internal.parsers.DOMParser.parse(DOMParser.java:243)
[junit4] 2> at com.sun.org.apache.xerces.internal.jaxp.DocumentBuilderImpl.parse(DocumentBuilderImpl.java:339)
[junit4] 2> at org.apache.solr.core.Config.<init>(Config.java:143)
[junit4] 2> ... 12 more
[junit4] 2> 1040003 WARN (coreLoadExecutor-4451-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1040003 INFO (coreLoadExecutor-4451-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1040013 INFO (coreLoadExecutor-4451-thread-1) [ ] o.a.s.s.IndexSchema [core2] Schema name=tiny
[junit4] 2> 1040013 WARN (coreLoadExecutor-4451-thread-2) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1040013 INFO (coreLoadExecutor-4451-thread-2) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1040036 WARN (coreLoadExecutor-4451-thread-3) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1040037 INFO (coreLoadExecutor-4451-thread-3) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1040040 INFO (coreLoadExecutor-4451-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 1040043 INFO (coreLoadExecutor-4451-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'core2' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/core2
[junit4] 2> 1040043 INFO (coreLoadExecutor-4451-thread-2) [ ] o.a.s.c.Config Ignoring exception matching \QSAXParseException\E
[junit4] 2> 1040044 INFO (coreLoadExecutor-4451-thread-1) [ x:core2] o.a.s.c.SolrCore [[core2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/core2], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/core2/data/]
[junit4] 2> 1040044 ERROR (coreLoadExecutor-4451-thread-2) [ ] o.a.s.c.CoreContainer Error creating core [badSchema1]: Could not load conf for core badSchema1: Can't load schema /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badSchema1/conf/schema.xml: org.xml.sax.SAXParseException; systemId: solrres:/schema.xml; lineNumber: 34; columnNumber: 5; The element type "filedType" must be terminated by the matching end-tag "</filedType>".
[junit4] 2> org.apache.solr.common.SolrException: Could not load conf for core badSchema1: Can't load schema /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badSchema1/conf/schema.xml: org.xml.sax.SAXParseException; systemId: solrres:/schema.xml; lineNumber: 34; columnNumber: 5; The element type "filedType" must be terminated by the matching end-tag "</filedType>".
[junit4] 2> at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:85)
[junit4] 2> at org.apache.solr.core.CoreContainer.create(CoreContainer.java:840)
[junit4] 2> at org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Can't load schema /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badSchema1/conf/schema.xml: org.xml.sax.SAXParseException; systemId: solrres:/schema.xml; lineNumber: 34; columnNumber: 5; The element type "filedType" must be terminated by the matching end-tag "</filedType>".
[junit4] 2> at org.apache.solr.schema.IndexSchema.readSchema(IndexSchema.java:598)
[junit4] 2> at org.apache.solr.schema.IndexSchema.<init>(IndexSchema.java:183)
[junit4] 2> at org.apache.solr.schema.IndexSchemaFactory.create(IndexSchemaFactory.java:56)
[junit4] 2> at org.apache.solr.schema.IndexSchemaFactory.buildIndexSchema(IndexSchemaFactory.java:75)
[junit4] 2> at org.apache.solr.core.ConfigSetService.createIndexSchema(ConfigSetService.java:107)
[junit4] 2> at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:78)
[junit4] 2> ... 7 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: org.xml.sax.SAXParseException; systemId: solrres:/schema.xml; lineNumber: 34; columnNumber: 5; The element type "filedType" must be terminated by the matching end-tag "</filedType>".
[junit4] 2> at org.apache.solr.core.Config.<init>(Config.java:154)
[junit4] 2> at org.apache.solr.core.Config.<init>(Config.java:90)
[junit4] 2> at org.apache.solr.schema.IndexSchema.readSchema(IndexSchema.java:460)
[junit4] 2> ... 12 more
[junit4] 2> Caused by: org.xml.sax.SAXParseException; systemId: solrres:/schema.xml; lineNumber: 34; columnNumber: 5; The element type "filedType" must be terminated by the matching end-tag "</filedType>".
[junit4] 2> at com.sun.org.apache.xerces.internal.util.ErrorHandlerWrapper.createSAXParseException(ErrorHandlerWrapper.java:203)
[junit4] 2> at com.sun.org.apache.xerces.internal.util.ErrorHandlerWrapper.fatalError(ErrorHandlerWrapper.java:177)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLErrorReporter.reportError(XMLErrorReporter.java:400)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLErrorReporter.reportError(XMLErrorReporter.java:327)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLScanner.reportFatalError(XMLScanner.java:1472)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanEndElement(XMLDocumentFragmentScannerImpl.java:1749)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl$FragmentContentDriver.next(XMLDocumentFragmentScannerImpl.java:2967)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(XMLDocumentScannerImpl.java:602)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.next(XMLNSDocumentScannerImpl.java:112)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanDocument(XMLDocumentFragmentScannerImpl.java:505)
[junit4] 2> at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(XML11Configuration.java:841)
[junit4] 2> at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(XML11Configuration.java:770)
[junit4] 2> at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(XMLParser.java:141)
[junit4] 2> at com.sun.org.apache.xerces.internal.parsers.DOMParser.parse(DOMParser.java:243)
[junit4] 2> at com.sun.org.apache.xerces.internal.jaxp.DocumentBuilderImpl.parse(DocumentBuilderImpl.java:339)
[junit4] 2> at org.apache.solr.core.Config.<init>(Config.java:143)
[junit4] 2> ... 14 more
[junit4] 2> 1040044 INFO (coreLoadExecutor-4451-thread-3) [ ] o.a.s.s.IndexSchema [core1] Schema name=tiny
[junit4] 2> 1040046 INFO (coreLoadExecutor-4451-thread-2) [ ] o.a.s.c.Config Ignoring exception matching \QSAXParseException\E
[junit4] 2> 1040046 INFO (coreLoadExecutor-4451-thread-3) [ ] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 1040047 ERROR (coreLoadExecutor-4451-thread-2) [ ] o.a.s.c.CoreContainer Error creating core [badConfig1]: Could not load conf for core badConfig1: Error loading solr config from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badConfig1/conf/solrconfig.xml
[junit4] 2> org.apache.solr.common.SolrException: Could not load conf for core badConfig1: Error loading solr config from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badConfig1/conf/solrconfig.xml
[junit4] 2> at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:85)
[junit4] 2> at org.apache.solr.core.CoreContainer.create(CoreContainer.java:840)
[junit4] 2> at org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Error loading solr config from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badConfig1/conf/solrconfig.xml
[junit4] 2> at org.apache.solr.core.SolrConfig.readFromResourceLoader(SolrConfig.java:187)
[junit4] 2> at org.apache.solr.core.ConfigSetService.createSolrConfig(ConfigSetService.java:97)
[junit4] 2> at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:77)
[junit4] 2> ... 7 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: org.xml.sax.SAXParseException; systemId: solrres:/solrconfig.xml; lineNumber: 56; columnNumber: 5; The element type "reqsthalr" must be terminated by the matching end-tag "</reqsthalr>".
[junit4] 2> at org.apache.solr.core.Config.<init>(Config.java:154)
[junit4] 2> at org.apache.solr.core.Config.<init>(Config.java:90)
[junit4] 2> at org.apache.solr.core.SolrConfig.<init>(SolrConfig.java:202)
[junit4] 2> at org.apache.solr.core.SolrConfig.readFromResourceLoader(SolrConfig.java:179)
[junit4] 2> ... 9 more
[junit4] 2> Caused by: org.xml.sax.SAXParseException; systemId: solrres:/solrconfig.xml; lineNumber: 56; columnNumber: 5; The element type "reqsthalr" must be terminated by the matching end-tag "</reqsthalr>".
[junit4] 2> at com.sun.org.apache.xerces.internal.util.ErrorHandlerWrapper.createSAXParseException(ErrorHandlerWrapper.java:203)
[junit4] 2> at com.sun.org.apache.xerces.internal.util.ErrorHandlerWrapper.fatalError(ErrorHandlerWrapper.java:177)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLErrorReporter.reportError(XMLErrorReporter.java:400)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLErrorReporter.reportError(XMLErrorReporter.java:327)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLScanner.reportFatalError(XMLScanner.java:1472)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanEndElement(XMLDocumentFragmentScannerImpl.java:1749)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl$FragmentContentDriver.next(XMLDocumentFragmentScannerImpl.java:2967)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(XMLDocumentScannerImpl.java:602)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.next(XMLNSDocumentScannerImpl.java:112)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanDocument(XMLDocumentFragmentScannerImpl.java:505)
[junit4] 2> at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(XML11Configuration.java:841)
[junit4] 2> at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(XML11Configuration.java:770)
[junit4] 2> at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(XMLParser.java:141)
[junit4] 2> at com.sun.org.apache.xerces.internal.parsers.DOMParser.parse(DOMParser.java:243)
[junit4] 2> at com.sun.org.apache.xerces.internal.jaxp.DocumentBuilderImpl.parse(DocumentBuilderImpl.java:339)
[junit4] 2> at org.apache.solr.core.Config.<init>(Config.java:143)
[junit4] 2> ... 12 more
[junit4] 2> 1040048 INFO (coreLoadExecutor-4451-thread-3) [ ] o.a.s.c.CoreContainer Creating SolrCore 'core1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/core1
[junit4] 2> 1040048 INFO (coreLoadExecutor-4451-thread-3) [ x:core1] o.a.s.c.SolrCore [[core1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/core1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/core1/data/]
[junit4] 2> 1040053 WARN (coreLoadExecutor-4451-thread-2) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1040054 INFO (coreLoadExecutor-4451-thread-2) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1040055 INFO (coreLoadExecutor-4451-thread-1) [ x:core2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=90.0634765625, floorSegmentMB=0.4462890625, forceMergeDeletesPctAllowed=1.7418993874938848, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7999171742028055
[junit4] 2> 1040056 INFO (coreLoadExecutor-4451-thread-3) [ x:core1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=90.0634765625, floorSegmentMB=0.4462890625, forceMergeDeletesPctAllowed=1.7418993874938848, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7999171742028055
[junit4] 2> 1040060 INFO (coreLoadExecutor-4451-thread-2) [ ] o.a.s.c.Config Ignoring exception matching \QSAXParseException\E
[junit4] 2> 1040060 ERROR (coreLoadExecutor-4451-thread-2) [ ] o.a.s.c.CoreContainer Error creating core [badSchema2]: Could not load conf for core badSchema2: Can't load schema /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badSchema2/conf/schema.xml: org.xml.sax.SAXParseException; systemId: solrres:/schema.xml; lineNumber: 34; columnNumber: 5; The element type "filedType" must be terminated by the matching end-tag "</filedType>".
[junit4] 2> org.apache.solr.common.SolrException: Could not load conf for core badSchema2: Can't load schema /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badSchema2/conf/schema.xml: org.xml.sax.SAXParseException; systemId: solrres:/schema.xml; lineNumber: 34; columnNumber: 5; The element type "filedType" must be terminated by the matching end-tag "</filedType>".
[junit4] 2> at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:85)
[junit4] 2> at org.apache.solr.core.CoreContainer.create(CoreContainer.java:840)
[junit4] 2> at org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Can't load schema /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badSchema2/conf/schema.xml: org.xml.sax.SAXParseException; systemId: solrres:/schema.xml; lineNumber: 34; columnNumber: 5; The element type "filedType" must be terminated by the matching end-tag "</filedType>".
[junit4] 2> at org.apache.solr.schema.IndexSchema.readSchema(IndexSchema.java:598)
[junit4] 2> at org.apache.solr.schema.IndexSchema.<init>(IndexSchema.java:183)
[junit4] 2> at org.apache.solr.schema.IndexSchemaFactory.create(IndexSchemaFactory.java:56)
[junit4] 2> at org.apache.solr.schema.IndexSchemaFactory.buildIndexSchema(IndexSchemaFactory.java:75)
[junit4] 2> at org.apache.solr.core.ConfigSetService.createIndexSchema(ConfigSetService.java:107)
[junit4] 2> at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:78)
[junit4] 2> ... 7 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: org.xml.sax.SAXParseException; systemId: solrres:/schema.xml; lineNumber: 34; columnNumber: 5; The element type "filedType" must be terminated by the matching end-tag "</filedType>".
[junit4] 2> at org.apache.solr.core.Config.<init>(Config.java:154)
[junit4] 2> at org.apache.solr.core.Config.<init>(Config.java:90)
[junit4] 2> at org.apache.solr.schema.IndexSchema.readSchema(IndexSchema.java:460)
[junit4] 2> ... 12 more
[junit4] 2> Caused by: org.xml.sax.SAXParseException; systemId: solrres:/schema.xml; lineNumber: 34; columnNumber: 5; The element type "filedType" must be terminated by the matching end-tag "</filedType>".
[junit4] 2> at com.sun.org.apache.xerces.internal.util.ErrorHandlerWrapper.createSAXParseException(ErrorHandlerWrapper.java:203)
[junit4] 2> at com.sun.org.apache.xerces.internal.util.ErrorHandlerWrapper.fatalError(ErrorHandlerWrapper.java:177)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLErrorReporter.reportError(XMLErrorReporter.java:400)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLErrorReporter.reportError(XMLErrorReporter.java:327)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLScanner.reportFatalError(XMLScanner.java:1472)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanEndElement(XMLDocumentFragmentScannerImpl.java:1749)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl$FragmentContentDriver.next(XMLDocumentFragmentScannerImpl.java:2967)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(XMLDocumentScannerImpl.java:602)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.next(XMLNSDocumentScannerImpl.java:112)
[junit4] 2> at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanDocument(XMLDocumentFragmentScannerImpl.java:505)
[junit4] 2> at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(XML11Configuration.java:841)
[junit4] 2> at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(XML11Configuration.java:770)
[junit4] 2> at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(XMLParser.java:141)
[junit4] 2> at com.sun.org.apache.xerces.internal.parsers.DOMParser.parse(DOMParser.java:243)
[junit4] 2> at com.sun.org.apache.xerces.internal.jaxp.DocumentBuilderImpl.parse(DocumentBuilderImpl.java:339)
[junit4] 2> at org.apache.solr.core.Config.<init>(Config.java:143)
[junit4] 2> ... 14 more
[junit4] 2> 1040061 INFO (coreLoadExecutor-4451-thread-3) [ x:core1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1040061 INFO (coreLoadExecutor-4451-thread-3) [ x:core1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1040061 INFO (coreLoadExecutor-4451-thread-3) [ x:core1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=51.87890625, floorSegmentMB=1.6171875, forceMergeDeletesPctAllowed=27.205798657855695, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3027877807731628
[junit4] 2> 1040061 INFO (coreLoadExecutor-4451-thread-3) [ x:core1] o.a.s.s.SolrIndexSearcher Opening [Searcher@17cda321[core1] main]
[junit4] 2> 1040062 INFO (coreLoadExecutor-4451-thread-3) [ x:core1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/core1/conf
[junit4] 2> 1040062 INFO (coreLoadExecutor-4451-thread-3) [ x:core1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1040062 INFO (coreLoadExecutor-4451-thread-1) [ x:core2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1040062 INFO (coreLoadExecutor-4451-thread-1) [ x:core2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1040062 INFO (searcherExecutor-4453-thread-1-processing-x:core1) [ x:core1] o.a.s.c.SolrCore [core1] Registered new searcher Searcher@17cda321[core1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1040062 INFO (coreLoadExecutor-4451-thread-1) [ x:core2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=51.87890625, floorSegmentMB=1.6171875, forceMergeDeletesPctAllowed=27.205798657855695, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3027877807731628
[junit4] 2> 1040062 INFO (coreLoadExecutor-4451-thread-1) [ x:core2] o.a.s.s.SolrIndexSearcher Opening [Searcher@52fc6d0a[core2] main]
[junit4] 2> 1040062 INFO (coreLoadExecutor-4451-thread-1) [ x:core2] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/core2/conf
[junit4] 2> 1040063 INFO (coreLoadExecutor-4451-thread-1) [ x:core2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1040063 INFO (searcherExecutor-4452-thread-1-processing-x:core2) [ x:core2] o.a.s.c.SolrCore [core2] Registered new searcher Searcher@52fc6d0a[core2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1040064 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1040064 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@37c69d72
[junit4] 2> 1040066 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a5a6fae[core1] main]
[junit4] 2> 1040067 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1040067 INFO (searcherExecutor-4453-thread-1) [ ] o.a.s.c.SolrCore [core1] Registered new searcher Searcher@1a5a6fae[core1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C7)))}
[junit4] 2> 1040067 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ ] o.a.s.c.S.Request [core1] webapp=null path=null params={q={!prefix+f%3Dv_t}hel&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 1040069 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ ] o.a.s.c.S.Request [core1] webapp=null path=null params={q={!raw+f%3Dv_t}hello&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 1040069 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ ] o.a.s.c.S.Request [core1] webapp=null path=null params={q={!raw+f%3Dv_t}Hello&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 1040069 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ ] o.a.s.c.S.Request [core1] webapp=null path=null params={q={!raw+f%3Dv_f}1.5&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 1040088 WARN (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1040088 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1040095 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ ] o.a.s.s.IndexSchema [badConfig1] Schema name=tiny
[junit4] 2> 1040096 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ ] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 1040098 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ ] o.a.s.c.CoreContainer Creating SolrCore 'badConfig1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badConfig1
[junit4] 2> 1040098 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig1] o.a.s.c.SolrCore [[badConfig1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badConfig1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badConfig1/data/]
[junit4] 2> 1040100 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=39, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6151273304137139]
[junit4] 2> 1040107 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1040108 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1040108 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=40.6357421875, floorSegmentMB=1.619140625, forceMergeDeletesPctAllowed=17.173114620163034, segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6148574850448347
[junit4] 2> 1040108 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3982edcf[badConfig1] main]
[junit4] 2> 1040108 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badConfig1/conf
[junit4] 2> 1040109 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1040109 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig1] o.a.s.c.SolrCores Opening transient core badConfig1
[junit4] 2> 1040110 INFO (searcherExecutor-4466-thread-1-processing-x:badConfig1) [ x:badConfig1] o.a.s.c.SolrCore [badConfig1] Registered new searcher Searcher@3982edcf[badConfig1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1040115 WARN (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1040116 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1040120 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig1] o.a.s.s.IndexSchema [badConfig2] Schema name=tiny
[junit4] 2> 1040122 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig1] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 1040123 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig1] o.a.s.c.CoreContainer Creating SolrCore 'badConfig2' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badConfig2
[junit4] 2> 1040124 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig2] o.a.s.c.SolrCore [[badConfig2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badConfig2], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badConfig2/data/]
[junit4] 2> 1040125 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=29, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=65.8427734375, floorSegmentMB=1.0634765625, forceMergeDeletesPctAllowed=15.252038226831772, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1040131 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1040131 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1040132 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=18, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3462892995749447]
[junit4] 2> 1040132 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig2] o.a.s.s.SolrIndexSearcher Opening [Searcher@44135503[badConfig2] main]
[junit4] 2> 1040132 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig2] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badConfig2/conf
[junit4] 2> 1040132 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1040132 INFO (searcherExecutor-4470-thread-1-processing-x:badConfig2) [ x:badConfig2] o.a.s.c.SolrCore [badConfig2] Registered new searcher Searcher@44135503[badConfig2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1040133 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig2] o.a.s.c.SolrCores Opening transient core badConfig2
[junit4] 2> 1040150 WARN (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig2] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1040151 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1040157 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig2] o.a.s.s.IndexSchema [badSchema1] Schema name=tiny
[junit4] 2> 1040158 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig2] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 1040159 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badConfig2] o.a.s.c.CoreContainer Creating SolrCore 'badSchema1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badSchema1
[junit4] 2> 1040159 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema1] o.a.s.c.SolrCore [[badSchema1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badSchema1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badSchema1/data/]
[junit4] 2> 1040160 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=21, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6277019656669208]
[junit4] 2> 1040168 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1040168 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1040168 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=17, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3857664003482403]
[junit4] 2> 1040168 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4bcafd62[badSchema1] main]
[junit4] 2> 1040168 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badSchema1/conf
[junit4] 2> 1040168 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1040169 INFO (searcherExecutor-4474-thread-1-processing-x:badSchema1) [ x:badSchema1] o.a.s.c.SolrCore [badSchema1] Registered new searcher Searcher@4bcafd62[badSchema1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1040169 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema1] o.a.s.c.SolrCores Opening transient core badSchema1
[junit4] 2> 1040176 WARN (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1040176 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1040180 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema1] o.a.s.s.IndexSchema [badSchema2] Schema name=tiny
[junit4] 2> 1040182 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema1] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 1040183 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema1] o.a.s.c.CoreContainer Creating SolrCore 'badSchema2' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badSchema2
[junit4] 2> 1040183 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.c.SolrCore [[badSchema2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badSchema2], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badSchema2/data/]
[junit4] 2> 1040184 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=7.1728515625, floorSegmentMB=1.880859375, forceMergeDeletesPctAllowed=27.078282333502727, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6808566463388556
[junit4] 2> 1040191 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1040192 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1040192 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=4.5146484375, floorSegmentMB=0.669921875, forceMergeDeletesPctAllowed=1.3622535341754538, segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1040192 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.s.SolrIndexSearcher Opening [Searcher@16bbd8a9[badSchema2] main]
[junit4] 2> 1040192 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-001/badSchema2/conf
[junit4] 2> 1040192 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1040193 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.c.SolrCores Opening transient core badSchema2
[junit4] 2> 1040193 INFO (searcherExecutor-4478-thread-1-processing-x:badSchema2) [ x:badSchema2] o.a.s.c.SolrCore [badSchema2] Registered new searcher Searcher@16bbd8a9[badSchema2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1040194 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1040194 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@37c69d72
[junit4] 2> 1040196 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.s.SolrIndexSearcher Opening [Searcher@211e5c66[core1] main]
[junit4] 2> 1040196 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1040196 INFO (searcherExecutor-4453-thread-1-processing-x:badSchema2) [ x:badSchema2] o.a.s.c.SolrCore [core1] Registered new searcher Searcher@211e5c66[core1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.3.0):C7)))}
[junit4] 2> 1040196 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.c.S.Request [core1] webapp=null path=null params={q={!prefix+f%3Dv_t}hel&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 1040197 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.c.S.Request [core1] webapp=null path=null params={q={!raw+f%3Dv_t}hello&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 1040198 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.c.S.Request [core1] webapp=null path=null params={q={!raw+f%3Dv_t}Hello&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 1040198 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.c.S.Request [core1] webapp=null path=null params={q={!raw+f%3Dv_f}1.5&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 1040199 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1040199 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2f4e46a2
[junit4] 2> 1040201 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.s.SolrIndexSearcher Opening [Searcher@45819806[badConfig1] main]
[junit4] 2> 1040201 INFO (searcherExecutor-4466-thread-1-processing-x:badSchema2) [ x:badSchema2] o.a.s.c.SolrCore [badConfig1] Registered new searcher Searcher@45819806[badConfig1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C7)))}
[junit4] 2> 1040201 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1040202 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.c.S.Request [badConfig1] webapp=null path=null params={q={!prefix+f%3Dv_t}hel&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 1040202 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.c.S.Request [badConfig1] webapp=null path=null params={q={!raw+f%3Dv_t}hello&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 1040203 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.c.S.Request [badConfig1] webapp=null path=null params={q={!raw+f%3Dv_t}Hello&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 1040203 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.c.S.Request [badConfig1] webapp=null path=null params={q={!raw+f%3Dv_f}1.5&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 1040203 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1040203 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4a994162
[junit4] 2> 1040205 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.s.SolrIndexSearcher Opening [Searcher@549f4ce5[badSchema1] main]
[junit4] 2> 1040206 INFO (searcherExecutor-4474-thread-1-processing-x:badSchema2) [ x:badSchema2] o.a.s.c.SolrCore [badSchema1] Registered new searcher Searcher@549f4ce5[badSchema1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C7)))}
[junit4] 2> 1040206 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1040206 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.c.S.Request [badSchema1] webapp=null path=null params={q={!prefix+f%3Dv_t}hel&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 1040207 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.c.S.Request [badSchema1] webapp=null path=null params={q={!raw+f%3Dv_t}hello&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 1040207 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.c.S.Request [badSchema1] webapp=null path=null params={q={!raw+f%3Dv_t}Hello&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 1040207 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.c.S.Request [badSchema1] webapp=null path=null params={q={!raw+f%3Dv_f}1.5&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 1040207 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1884519738
[junit4] 2> 1040208 INFO (coreCloseExecutor-4488-thread-1-processing-x:badSchema2) [ x:core1] o.a.s.c.SolrCore [core1] CLOSING SolrCore org.apache.solr.core.SolrCore@6a6ba95
[junit4] 2> 1040208 INFO (coreCloseExecutor-4488-thread-2-processing-x:badSchema2) [ x:core2] o.a.s.c.SolrCore [core2] CLOSING SolrCore org.apache.solr.core.SolrCore@257f3131
[junit4] 2> 1040209 INFO (coreCloseExecutor-4488-thread-3-processing-x:badSchema2) [ x:badConfig1] o.a.s.c.SolrCore [badConfig1] CLOSING SolrCore org.apache.solr.core.SolrCore@5ac50474
[junit4] 2> 1040209 INFO (coreCloseExecutor-4488-thread-4-processing-x:badSchema2) [ x:badConfig2] o.a.s.c.SolrCore [badConfig2] CLOSING SolrCore org.apache.solr.core.SolrCore@3f470312
[junit4] 2> 1040209 INFO (coreCloseExecutor-4488-thread-5-processing-x:badSchema2) [ x:badSchema1] o.a.s.c.SolrCore [badSchema1] CLOSING SolrCore org.apache.solr.core.SolrCore@3c7c21e2
[junit4] 2> 1040211 INFO (coreCloseExecutor-4488-thread-6-processing-x:badSchema2) [ x:badSchema2] o.a.s.c.SolrCore [badSchema2] CLOSING SolrCore org.apache.solr.core.SolrCore@74fcafa9
[junit4] 2> 1040211 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.SolrTestCaseJ4 ###Ending testBadConfigsGenerateErrors
[junit4] 2> 1040213 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.SolrTestCaseJ4 ###Starting testCreateTransientFromAdmin
[junit4] 2> 1040223 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 1040223 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.c.SolrCores Allocating transient cache for 4 transient cores
[junit4] 2> 1040235 WARN (coreLoadExecutor-4495-thread-3-processing-x:badSchema2) [ x:badSchema2] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1040235 INFO (coreLoadExecutor-4495-thread-3-processing-x:badSchema2) [ x:badSchema2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1040236 WARN (coreLoadExecutor-4495-thread-1-processing-x:badSchema2) [ x:badSchema2] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1040236 INFO (coreLoadExecutor-4495-thread-1-processing-x:badSchema2) [ x:badSchema2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1040240 INFO (coreLoadExecutor-4495-thread-3-processing-x:badSchema2) [ x:badSchema2] o.a.s.s.IndexSchema [collection5] Schema name=tiny
[junit4] 2> 1040242 WARN (coreLoadExecutor-4495-thread-2-processing-x:badSchema2) [ x:badSchema2] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1040242 INFO (coreLoadExecutor-4495-thread-3-processing-x:badSchema2) [ x:badSchema2] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 1040243 INFO (coreLoadExecutor-4495-thread-2-processing-x:badSchema2) [ x:badSchema2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1040253 INFO (coreLoadExecutor-4495-thread-3-processing-x:badSchema2) [ x:badSchema2] o.a.s.c.CoreContainer Creating SolrCore 'collection5' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-002/collection5
[junit4] 2> 1040253 INFO (coreLoadExecutor-4495-thread-3-processing-x:badSchema2) [ x:collection5] o.a.s.c.SolrCore [[collection5] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-002/collection5], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-002/collection5/data/]
[junit4] 2> 1040254 INFO (coreLoadExecutor-4495-thread-3-processing-x:badSchema2) [ x:collection5] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=90.0634765625, floorSegmentMB=0.4462890625, forceMergeDeletesPctAllowed=1.7418993874938848, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7999171742028055
[junit4] 2> 1040265 INFO (coreLoadExecutor-4495-thread-1-processing-x:badSchema2) [ x:badSchema2] o.a.s.s.IndexSchema [collection1] Schema name=tiny
[junit4] 2> 1040266 INFO (coreLoadExecutor-4495-thread-2-processing-x:badSchema2) [ x:badSchema2] o.a.s.s.IndexSchema [collection2] Schema name=tiny
[junit4] 2> 1040267 INFO (coreLoadExecutor-4495-thread-1-processing-x:badSchema2) [ x:badSchema2] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 1040268 INFO (coreLoadExecutor-4495-thread-1-processing-x:badSchema2) [ x:badSchema2] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-002/collection1
[junit4] 2> 1040268 INFO (coreLoadExecutor-4495-thread-2-processing-x:badSchema2) [ x:badSchema2] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 1040269 INFO (coreLoadExecutor-4495-thread-1-processing-x:badSchema2) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-002/collection1/data/]
[junit4] 2> 1040269 INFO (coreLoadExecutor-4495-thread-1-processing-x:badSchema2) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=90.0634765625, floorSegmentMB=0.4462890625, forceMergeDeletesPctAllowed=1.7418993874938848, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7999171742028055
[junit4] 2> 1040269 INFO (coreLoadExecutor-4495-thread-2-processing-x:badSchema2) [ x:badSchema2] o.a.s.c.CoreContainer Creating SolrCore 'collection2' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-002/collection2
[junit4] 2> 1040269 INFO (coreLoadExecutor-4495-thread-2-processing-x:badSchema2) [ x:collection2] o.a.s.c.SolrCore [[collection2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-002/collection2], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-002/collection2/data/]
[junit4] 2> 1040271 INFO (coreLoadExecutor-4495-thread-2-processing-x:badSchema2) [ x:collection2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=90.0634765625, floorSegmentMB=0.4462890625, forceMergeDeletesPctAllowed=1.7418993874938848, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7999171742028055
[junit4] 2> 1040271 INFO (coreLoadExecutor-4495-thread-3-processing-x:badSchema2) [ x:collection5] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1040271 INFO (coreLoadExecutor-4495-thread-3-processing-x:badSchema2) [ x:collection5] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1040271 INFO (coreLoadExecutor-4495-thread-3-processing-x:badSchema2) [ x:collection5] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=51.87890625, floorSegmentMB=1.6171875, forceMergeDeletesPctAllowed=27.205798657855695, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3027877807731628
[junit4] 2> 1040271 INFO (coreLoadExecutor-4495-thread-3-processing-x:badSchema2) [ x:collection5] o.a.s.s.SolrIndexSearcher Opening [Searcher@712a210[collection5] main]
[junit4] 2> 1040271 INFO (coreLoadExecutor-4495-thread-3-processing-x:badSchema2) [ x:collection5] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-002/collection5/conf
[junit4] 2> 1040272 INFO (coreLoadExecutor-4495-thread-3-processing-x:badSchema2) [ x:collection5] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1040272 INFO (searcherExecutor-4496-thread-1-processing-x:collection5) [ x:collection5] o.a.s.c.SolrCore [collection5] Registered new searcher Searcher@712a210[collection5] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1040276 INFO (coreLoadExecutor-4495-thread-1-processing-x:badSchema2) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1040276 INFO (coreLoadExecutor-4495-thread-1-processing-x:badSchema2) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1040276 INFO (coreLoadExecutor-4495-thread-1-processing-x:badSchema2) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=51.87890625, floorSegmentMB=1.6171875, forceMergeDeletesPctAllowed=27.205798657855695, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3027877807731628
[junit4] 2> 1040276 INFO (coreLoadExecutor-4495-thread-2-processing-x:badSchema2) [ x:collection2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1040276 INFO (coreLoadExecutor-4495-thread-2-processing-x:badSchema2) [ x:collection2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1040276 INFO (coreLoadExecutor-4495-thread-1-processing-x:badSchema2) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@373c5356[collection1] main]
[junit4] 2> 1040276 INFO (coreLoadExecutor-4495-thread-2-processing-x:badSchema2) [ x:collection2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=51.87890625, floorSegmentMB=1.6171875, forceMergeDeletesPctAllowed=27.205798657855695, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3027877807731628
[junit4] 2> 1040277 INFO (coreLoadExecutor-4495-thread-1-processing-x:badSchema2) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-002/collection1/conf
[junit4] 2> 1040277 INFO (coreLoadExecutor-4495-thread-2-processing-x:badSchema2) [ x:collection2] o.a.s.s.SolrIndexSearcher Opening [Searcher@728a9c52[collection2] main]
[junit4] 2> 1040277 INFO (coreLoadExecutor-4495-thread-2-processing-x:badSchema2) [ x:collection2] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-002/collection2/conf
[junit4] 2> 1040277 INFO (coreLoadExecutor-4495-thread-1-processing-x:badSchema2) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1040277 INFO (coreLoadExecutor-4495-thread-2-processing-x:badSchema2) [ x:collection2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1040277 INFO (coreLoadExecutor-4495-thread-2-processing-x:badSchema2) [ x:collection2] o.a.s.c.SolrCores Opening transient core collection2
[junit4] 2> 1040277 INFO (searcherExecutor-4499-thread-1-processing-x:collection2) [ x:collection2] o.a.s.c.SolrCore [collection2] Registered new searcher Searcher@728a9c52[collection2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1040279 INFO (searcherExecutor-4498-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@373c5356[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1040284 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.h.a.CoreAdminOperation core create command loadOnStartup=true&transient=true&name=core1&action=CREATE&wt=xml
[junit4] 2> 1040290 WARN (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1040291 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1040295 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.s.IndexSchema [core1] Schema name=tiny
[junit4] 2> 1040297 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 1040298 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:badSchema2] o.a.s.c.CoreContainer Creating SolrCore 'core1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-002/core1
[junit4] 2> 1040298 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:core1] o.a.s.c.SolrCore [[core1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-002/core1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-002/core1/data/]
[junit4] 2> 1040299 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:core1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=40, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1040305 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:core1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1040305 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:core1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1040305 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:core1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=14, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5338138984370179]
[junit4] 2> 1040305 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:core1] o.a.s.s.SolrIndexSearcher Opening [Searcher@223ef39[core1] main]
[junit4] 2> 1040306 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:core1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-002/core1/conf
[junit4] 2> 1040306 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:core1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1040306 INFO (searcherExecutor-4509-thread-1-processing-x:core1) [ x:core1] o.a.s.c.SolrCore [core1] Registered new searcher Searcher@223ef39[core1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1040306 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:core1] o.a.s.c.SolrCores Opening transient core core1
[junit4] 2> 1040307 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:core1] o.a.s.h.a.CoreAdminOperation core create command loadOnStartup=false&transient=true&name=core2&action=CREATE&wt=xml
[junit4] 2> 1040312 WARN (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:core1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1040312 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:core1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1040317 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:core1] o.a.s.s.IndexSchema [core2] Schema name=tiny
[junit4] 2> 1040319 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:core1] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
[junit4] 2> 1040320 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:core1] o.a.s.c.CoreContainer Creating SolrCore 'core2' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-002/core2
[junit4] 2> 1040320 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:core2] o.a.s.c.SolrCore [[core2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-002/core2], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestLazyCores_534FFA4EC31DFBC3-001/tempDir-002/core2/data/]
[junit4] 2> 1040321 INFO (TEST-TestLazyCores.testCreateTransientFromAdmin-seed#[534FFA4EC31DFBC3]) [ x:core2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByte
[...truncated too long message...]
yFactory, TestHighlightDedupGrouping, TestInfoStreamLogging, CacheHeaderTest, TestDocTermOrds, TestPHPSerializedResponseWriter, LeaderInitiatedRecoveryOnShardRestartTest, FastVectorHighlighterTest, DeleteShardTest, TestBadConfig, TestSortingResponseWriter, TestBinaryResponseWriter, TestCodecSupport, QueryEqualityTest, QueryResultKeyTest, TestMissingGroups, TestFieldCacheVsDocValues, TestFieldResource, TestLockTree, TestBlendedInfixSuggestions, UUIDFieldTest, TestSolr4Spatial, DisMaxRequestHandlerTest, TestDistributedSearch, CdcrReplicationDistributedZkTest, ResponseHeaderTest, TestDynamicFieldCollectionResource, TestSchemaNameResource, JSONWriterTest, TestRTimerTree, MBeansHandlerTest, TestSolrQueryParserResource, TestHdfsUpdateLog, XsltUpdateRequestHandlerTest, TestCloudManagedSchema, DataDrivenBlockJoinTest, SolrCloudExampleTest, ScriptEngineTest, BigEndianAscendingWordDeserializerTest, DistribDocExpirationUpdateProcessorTest, BasicZkTest, ZkSolrClientTest, TestRandomDVFaceting, TestFaceting, TestHashPartitioner, TestReload, TestCoreContainer, SolrCoreTest, SpellCheckComponentTest, BasicFunctionalityTest, TestLazyCores]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestLazyCores -Dtests.seed=534FFA4EC31DFBC3 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=nl-NL -Dtests.timezone=America/Blanc-Sablon -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, SolrCore, MockDirectoryWrapper]
[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:328)
[junit4] > at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:635)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:843)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:769)
[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:667)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:843)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:769)
[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:792)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:769)
[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:933)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:769)
[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:430)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:837)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:769)
[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([534FFA4EC31DFBC3]: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=8213, name=searcherExecutor-4567-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([534FFA4EC31DFBC3]:0)Throwable #3: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=8213, name=searcherExecutor-4567-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([534FFA4EC31DFBC3]:0)
[junit4] Completed [413/640 (1!)] on J0 in 178.20s, 8 tests, 1 failure, 2 errors <<< FAILURES!
[...truncated 61048 lines...]
[JENKINS-EA] Lucene-Solr-6.x-Linux (32bit/jdk-9-ea+140) - Build #
1977 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/1977/
Java: 32bit/jdk-9-ea+140 -server -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.update.AutoCommitTest.testMaxTime
Error Message:
expected:<3> but was:<4>
Stack Trace:
java.lang.AssertionError: expected:<3> but was:<4>
at __randomizedtesting.SeedInfo.seed([C6BFCBB3E5D52F1E:5C4BB6517B4FB322]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:302)
at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method)
at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:535)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
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:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
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(java.base@9-ea/Thread.java:843)
Build Log:
[...truncated 11433 lines...]
[junit4] Suite: org.apache.solr.update.AutoCommitTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.AutoCommitTest_C6BFCBB3E5D52F1E-001/init-core-data-001
[junit4] 2> 753408 INFO (SUITE-AutoCommitTest-seed#[C6BFCBB3E5D52F1E]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 753410 INFO (SUITE-AutoCommitTest-seed#[C6BFCBB3E5D52F1E]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 753411 INFO (SUITE-AutoCommitTest-seed#[C6BFCBB3E5D52F1E]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 753488 WARN (SUITE-AutoCommitTest-seed#[C6BFCBB3E5D52F1E]-worker) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 753490 INFO (SUITE-AutoCommitTest-seed#[C6BFCBB3E5D52F1E]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 753511 INFO (SUITE-AutoCommitTest-seed#[C6BFCBB3E5D52F1E]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 753582 WARN (SUITE-AutoCommitTest-seed#[C6BFCBB3E5D52F1E]-worker) [ ] o.a.s.s.IndexSchema [null] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 753583 INFO (SUITE-AutoCommitTest-seed#[C6BFCBB3E5D52F1E]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 753606 INFO (SUITE-AutoCommitTest-seed#[C6BFCBB3E5D52F1E]-worker) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 753610 INFO (coreLoadExecutor-2891-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 753688 WARN (coreLoadExecutor-2891-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 753690 INFO (coreLoadExecutor-2891-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 753708 INFO (coreLoadExecutor-2891-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 753778 WARN (coreLoadExecutor-2891-thread-1) [ ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 753780 INFO (coreLoadExecutor-2891-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 753801 INFO (coreLoadExecutor-2891-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 753801 INFO (coreLoadExecutor-2891-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.AutoCommitTest_C6BFCBB3E5D52F1E-001/init-core-data-001/]
[junit4] 2> 753802 INFO (coreLoadExecutor-2891-thread-1) [ ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c5f6a3
[junit4] 2> 753803 INFO (coreLoadExecutor-2891-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=20, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7532762821933638]
[junit4] 2> 753814 INFO (coreLoadExecutor-2891-thread-1) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 753814 INFO (coreLoadExecutor-2891-thread-1) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 753815 INFO (coreLoadExecutor-2891-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 753815 INFO (coreLoadExecutor-2891-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 753815 INFO (coreLoadExecutor-2891-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=935182918, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 753816 INFO (coreLoadExecutor-2891-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@100a9ff[collection1] main]
[junit4] 2> 753816 INFO (coreLoadExecutor-2891-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 753885 INFO (coreLoadExecutor-2891-thread-1) [ ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 754111 INFO (coreLoadExecutor-2891-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 754111 INFO (searcherExecutor-2892-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 754112 INFO (coreLoadExecutor-2891-thread-1) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1548515315439108096
[junit4] 2> 754112 INFO (searcherExecutor-2892-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 754113 INFO (searcherExecutor-2892-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 754113 INFO (searcherExecutor-2892-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 754114 INFO (searcherExecutor-2892-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 754114 INFO (searcherExecutor-2892-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 754115 INFO (SUITE-AutoCommitTest-seed#[C6BFCBB3E5D52F1E]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 754115 INFO (searcherExecutor-2892-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 754115 INFO (searcherExecutor-2892-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 754116 INFO (searcherExecutor-2892-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 754117 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCommitWithin
[junit4] 2> 754117 INFO (searcherExecutor-2892-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@100a9ff[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 754118 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:* (-1548515315444350976)} 0 1
[junit4] 2> 754120 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 754217 WARN (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 754218 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 754240 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 754326 WARN (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 754328 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 754349 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 754349 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.AutoCommitTest_C6BFCBB3E5D52F1E-001/init-core-data-001/]
[junit4] 2> 754349 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c5f6a3
[junit4] 2> 754372 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 754372 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 754372 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1e27c4f[collection1] main]
[junit4] 2> 754372 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 754374 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 754381 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 754382 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 754383 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 754383 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 754383 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 754383 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 754384 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 754385 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 754386 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1759388617, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 754386 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 754386 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 754386 WARN (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.SolrCore [collection1] PERFORMANCE WARNING: Overlapping onDeckSearchers=2
[junit4] 2> 754386 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1797913[collection1] main]
[junit4] 2> 754387 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@caa69a
[junit4] 2> 754387 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 754387 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1e27c4f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 754387 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 754388 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 754388 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 754388 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 754389 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 754389 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 754390 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 754390 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 754391 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 754392 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1797913[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 754397 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 (1548515315736903680)]} 0 1
[junit4] 2> 754398 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1
[junit4] 2> 758397 INFO (commitScheduler-2900-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 758400 INFO (commitScheduler-2900-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@14fd24e[collection1] main]
[junit4] 2> 758400 INFO (commitScheduler-2900-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 758401 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@14fd24e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c1)))}
[junit4] 2> 758651 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 (1548515320197545984)]} 0 4255
[junit4] 2> 758651 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 758652 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[529 (-1548515320199643136)]} 0 4256
[junit4] 2> 758652 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 759652 INFO (commitScheduler-2900-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 759654 INFO (commitScheduler-2900-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@b1f0ba[collection1] main]
[junit4] 2> 759654 INFO (commitScheduler-2900-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 759655 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@b1f0ba[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.3.0):c1)))}
[junit4] 2> 759903 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 759904 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 759904 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1548515321512460288)]} 0 5509
[junit4] 2> 759905 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1548515321513508864)]} 0 5509
[junit4] 2> 759905 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1548515321513508865)]} 0 5509
[junit4] 2> 759905 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1548515321513508866)]} 0 5509
[junit4] 2> 759905 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1548515321513508867)]} 0 5509
[junit4] 2> 759906 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1548515321514557440)]} 0 5510
[junit4] 2> 759906 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1548515321514557441)]} 0 5510
[junit4] 2> 759906 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1548515321514557442)]} 0 5510
[junit4] 2> 759906 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1548515321514557443)]} 0 5510
[junit4] 2> 759906 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1548515321514557444)]} 0 5510
[junit4] 2> 759906 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:500&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 759907 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1548515321515606016)]} 0 5511
[junit4] 2> 759907 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1548515321515606017)]} 0 5511
[junit4] 2> 759907 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1548515321515606018)]} 0 5511
[junit4] 2> 759907 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1548515321515606019)]} 0 5511
[junit4] 2> 759907 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1548515321515606020)]} 0 5511
[junit4] 2> 759907 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1548515321515606021)]} 0 5512
[junit4] 2> 759908 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1548515321515606022)]} 0 5512
[junit4] 2> 759908 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1548515321516654592)]} 0 5512
[junit4] 2> 759908 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1548515321516654593)]} 0 5512
[junit4] 2> 759908 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1548515321516654594)]} 0 5512
[junit4] 2> 759908 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 760907 INFO (commitScheduler-2900-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 760910 INFO (commitScheduler-2900-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@16af8f0[collection1] main]
[junit4] 2> 760910 INFO (commitScheduler-2900-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 760911 INFO (searcherExecutor-2897-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@16af8f0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.3.0):c10/9:delGen=1)))}
[junit4] 2> 761159 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:500&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 761160 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 761160 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCommitWithin
[junit4] 2> 761162 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMaxTime
[junit4] 2> 761163 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@d6e8de[collection1] realtime]
[junit4] 2> 761163 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:* (-1548515322831568896)} 0 0
[junit4] 2> 761164 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 761254 WARN (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 761255 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 761275 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 761361 WARN (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 761365 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 761400 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 761400 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.AutoCommitTest_C6BFCBB3E5D52F1E-001/init-core-data-001/]
[junit4] 2> 761400 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c5f6a3
[junit4] 2> 761420 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 761420 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 761421 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1792d02[collection1] main]
[junit4] 2> 761421 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 761423 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 761432 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 761434 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 761435 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 761435 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 761435 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 761436 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=72.8037109375, floorSegmentMB=2.0771484375, forceMergeDeletesPctAllowed=17.33347029428981, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 761436 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 761436 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 761436 WARN (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.SolrCore [collection1] PERFORMANCE WARNING: Overlapping onDeckSearchers=2
[junit4] 2> 761437 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1455de8[collection1] main]
[junit4] 2> 761437 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1c9cb96
[junit4] 2> 761437 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 761437 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 761438 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 761438 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 761439 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1792d02[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 761439 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 761440 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 761440 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 761440 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 761441 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 761442 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 761443 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 761443 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 761444 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 761444 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1455de8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 761448 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 (1548515323130413056)]} 0 0
[junit4] 2> 761448 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 762448 INFO (commitScheduler-2904-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 762451 INFO (commitScheduler-2904-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4a1083[collection1] main]
[junit4] 2> 762451 INFO (commitScheduler-2904-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 762451 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4a1083[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.3.0):c1)))}
[junit4] 2> 762699 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 (1548515324443230208)]} 0 1252
[junit4] 2> 762700 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 762700 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 762700 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{delete=[529 (-1548515324444278784)]} 0 0
[junit4] 2> 762701 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 763699 INFO (commitScheduler-2904-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 763702 INFO (commitScheduler-2904-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@871e11[collection1] main]
[junit4] 2> 763702 INFO (commitScheduler-2904-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 763703 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@871e11[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.3.0):c1)))}
[junit4] 2> 763952 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[550 (1548515325756047360)]} 0 2504
[junit4] 2> 763952 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 763952 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1548515325757095936)]} 0 2505
[junit4] 2> 763952 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1548515325757095937)]} 0 2505
[junit4] 2> 763952 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1548515325757095938)]} 0 2505
[junit4] 2> 763952 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1548515325757095939)]} 0 2505
[junit4] 2> 763952 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1548515325757095940)]} 0 2505
[junit4] 2> 763953 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1548515325758144512)]} 0 2505
[junit4] 2> 763953 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1548515325758144513)]} 0 2505
[junit4] 2> 763953 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1548515325758144514)]} 0 2505
[junit4] 2> 763953 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1548515325758144515)]} 0 2505
[junit4] 2> 763953 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1548515325758144516)]} 0 2505
[junit4] 2> 763953 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:500&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 764952 INFO (commitScheduler-2904-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 764954 INFO (commitScheduler-2904-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@13cdcfd[collection1] main]
[junit4] 2> 764954 INFO (commitScheduler-2904-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 764955 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@13cdcfd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.3.0):c1) Uninverting(_5(6.3.0):c11/9:delGen=1)))}
[junit4] 2> 766205 INFO (commitScheduler-2904-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 768462 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[531 (1548515327069913088)]} 0 7015
[junit4] 2> 768463 INFO (TEST-AutoCommitTest.testMaxTime-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMaxTime
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=AutoCommitTest -Dtests.method=testMaxTime -Dtests.seed=C6BFCBB3E5D52F1E -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-IM -Dtests.timezone=Mexico/BajaNorte -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 7.31s J2 | AutoCommitTest.testMaxTime <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<3> but was:<4>
[junit4] > at __randomizedtesting.SeedInfo.seed([C6BFCBB3E5D52F1E:5C4BB6517B4FB322]:0)
[junit4] > at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:302)
[junit4] > at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method)
[junit4] > at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
[junit4] > at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
[junit4] 2> 768467 INFO (commitScheduler-2904-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3b3af3[collection1] main]
[junit4] 2> 768468 INFO (commitScheduler-2904-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 768469 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMaxDocs
[junit4] 2> 768469 INFO (searcherExecutor-2901-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3b3af3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.3.0):c1) Uninverting(_5(6.3.0):c11/9:delGen=1) Uninverting(_6(6.3.0):c1)))}
[junit4] 2> 768470 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@c19f56[collection1] realtime]
[junit4] 2> 768470 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:* (-1548515330493513728)} 0 1
[junit4] 2> 768472 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 768570 WARN (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 768571 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 768588 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 768640 WARN (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 768641 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 768659 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 768660 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.AutoCommitTest_C6BFCBB3E5D52F1E-001/init-core-data-001/]
[junit4] 2> 768660 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c5f6a3
[junit4] 2> 768670 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 768670 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 768671 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@c43d46[collection1] main]
[junit4] 2> 768671 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 768672 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 768676 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 768677 INFO (searcherExecutor-2905-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 768677 INFO (searcherExecutor-2905-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 768678 INFO (searcherExecutor-2905-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 768678 INFO (searcherExecutor-2905-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 768678 INFO (searcherExecutor-2905-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 768678 INFO (searcherExecutor-2905-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 768679 INFO (searcherExecutor-2905-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 768679 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@103d4a0
[junit4] 2> 768679 INFO (searcherExecutor-2905-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 768680 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 768680 WARN (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.SolrCore [collection1] PERFORMANCE WARNING: Overlapping onDeckSearchers=2
[junit4] 2> 768680 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@239d4e[collection1] main]
[junit4] 2> 768680 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@23a897
[junit4] 2> 768680 INFO (searcherExecutor-2905-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 768681 INFO (searcherExecutor-2905-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@c43d46[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 768681 INFO (searcherExecutor-2905-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 768681 INFO (searcherExecutor-2905-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 768681 INFO (searcherExecutor-2905-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 768682 INFO (searcherExecutor-2905-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 768682 INFO (searcherExecutor-2905-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 768683 INFO (searcherExecutor-2905-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 768683 INFO (searcherExecutor-2905-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 768683 INFO (searcherExecutor-2905-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 768684 INFO (searcherExecutor-2905-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 768684 INFO (searcherExecutor-2905-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@239d4e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 768686 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1548515330720006144)]} 0 0
[junit4] 2> 768686 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[1 (1548515330721054720)]} 0 0
[junit4] 2> 768686 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[2 (1548515330721054721)]} 0 0
[junit4] 2> 768686 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[3 (1548515330721054722)]} 0 0
[junit4] 2> 768686 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[4 (1548515330721054723)]} 0 0
[junit4] 2> 768686 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[5 (1548515330721054724)]} 0 1
[junit4] 2> 768686 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[6 (1548515330721054725)]} 0 1
[junit4] 2> 768686 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[7 (1548515330721054726)]} 0 1
[junit4] 2> 768686 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[8 (1548515330721054727)]} 0 1
[junit4] 2> 768686 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[9 (1548515330721054728)]} 0 1
[junit4] 2> 768687 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[10 (1548515330721054729)]} 0 1
[junit4] 2> 768687 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[11 (1548515330722103296)]} 0 1
[junit4] 2> 768687 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[12 (1548515330722103297)]} 0 1
[junit4] 2> 768687 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[13 (1548515330722103298)]} 0 1
[junit4] 2> 768687 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 768687 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[14 (1548515330722103299)]} 0 2
[junit4] 2> 768688 INFO (commitScheduler-2908-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 768691 INFO (commitScheduler-2908-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@666e66[collection1] main]
[junit4] 2> 768691 INFO (commitScheduler-2908-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 768692 INFO (searcherExecutor-2905-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@666e66[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7(6.3.0):c15)))}
[junit4] 2> 768938 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[15 (1548515330985295872)]} 0 252
[junit4] 2> 768938 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 768939 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:15&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 768939 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[C6BFCBB3E5D52F1E]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMaxDocs
[junit4] 2> 768939 INFO (SUITE-AutoCommitTest-seed#[C6BFCBB3E5D52F1E]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 768939 INFO (SUITE-AutoCommitTest-seed#[C6BFCBB3E5D52F1E]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=29340974
[junit4] 2> 768939 INFO (coreCloseExecutor-2909-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1d3261b
[junit4] 2> 768940 INFO (coreCloseExecutor-2909-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
[junit4] 2> 768940 INFO (coreCloseExecutor-2909-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6eafe8
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.AutoCommitTest_C6BFCBB3E5D52F1E-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {range_facet_l_dv=Lucene50(blocksize=128), _version_=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), field_t=Lucene50(blocksize=128), multiDefault=Lucene50(blocksize=128), subject=Lucene50(blocksize=128), intDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), id=Lucene50(blocksize=128), range_facet_i_dv=Lucene50(blocksize=128), text=PostingsFormat(name=LuceneVarGapFixedInterval), range_facet_l=Lucene50(blocksize=128), timestamp=Lucene50(blocksize=128)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=1586, maxMBSortInHeap=6.1232935271782365, sim=ClassicSimilarity, locale=en-IM, timezone=Mexico/BajaNorte
[junit4] 2> NOTE: Linux 4.4.0-42-generic i386/Oracle Corporation 9-ea (32-bit)/cpus=12,threads=1,free=61585824,total=512229376
[junit4] 2> NOTE: All tests run in this JVM: [HdfsDirectoryFactoryTest, CloneFieldUpdateProcessorFactoryTest, TestReplicaProperties, TestConfigSetsAPI, CircularListTest, TestPerFieldSimilarityWithDefaultOverride, TestSolrDeletionPolicy1, CollectionsAPISolrJTest, ParsingFieldUpdateProcessorsTest, DeleteInactiveReplicaTest, TestUtils, TestDFISimilarityFactory, TestSolrCoreSnapshots, TestXmlQParser, BlobRepositoryCloudTest, TestLeaderElectionZkExpiry, TolerantUpdateProcessorTest, CdcrBootstrapTest, TestDistribIDF, FacetPivotSmallTest, SuggestComponentTest, TestRecovery, AddSchemaFieldsUpdateProcessorFactoryTest, TestLRUStatsCache, InfoHandlerTest, TestMacros, PingRequestHandlerTest, DOMUtilTest, CdcrRequestHandlerTest, TestStressReorder, TestReqParamsAPI, MultiThreadedOCPTest, DistributedFacetExistsSmallTest, DirectUpdateHandlerTest, DistributedQueueTest, TestSolrQueryResponse, StatelessScriptUpdateProcessorFactoryTest, TestSha256AuthenticationProvider, TestPhraseSuggestions, BinaryUpdateRequestHandlerTest, ResponseLogComponentTest, StatsReloadRaceTest, AnalyticsQueryTest, CoreAdminHandlerTest, TestManagedSchemaAPI, TestPostingsSolrHighlighter, TestFreeTextSuggestions, TestZkChroot, DirectUpdateHandlerOptimizeTest, TestSolrQueryParserDefaultOperatorResource, SuggesterTSTTest, SolrCoreCheckLockOnStartupTest, TestSQLHandlerNonCloud, HdfsUnloadDistributedZkTest, TestSubQueryTransformer, PrimUtilsTest, ReplicationFactorTest, TestImplicitCoreProperties, TestGroupingSearch, SOLR749Test, DistribCursorPagingTest, HdfsBasicDistributedZk2Test, HighlighterMaxOffsetTest, TestConfigReload, HardAutoCommitTest, SolrCLIZkUtilsTest, ShowFileRequestHandlerTest, TestCloudPivotFacet, PluginInfoTest, TestBinaryResponseWriter, QueryParsingTest, SSLMigrationTest, MinimalSchemaTest, CloudMLTQParserTest, TestAnalyzeInfixSuggestions, DocValuesMissingTest, TestReload, SpellPossibilityIteratorTest, TestFieldCollectionResource, TestExceedMaxTermLength, AutoCommitTest]
[junit4] Completed [239/640 (1!)] on J2 in 15.55s, 3 tests, 1 failure <<< FAILURES!
[...truncated 51209 lines...]