Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6794/ Java: 32bit/jdk1.8.0_141 -server -XX:+UseParallelGC
2 tests failed. FAILED: org.apache.solr.update.processor.AtomicUpdateProcessorFactoryTest.testMultipleThreads Error Message: Exception during query Stack Trace: java.lang.RuntimeException: Exception during query at __randomizedtesting.SeedInfo.seed([A80656A6D581D49E:8434212B5945A21A]:0) at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:886) at org.apache.solr.update.processor.AtomicUpdateProcessorFactoryTest.testMultipleThreads(AtomicUpdateProcessorFactoryTest.java:264) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1] xml response was: <?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result> </response> request was:q=int_i:17&wt=xml at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:879) ... 40 more FAILED: junit.framework.TestSuite.org.apache.solr.client.solrj.embedded.MergeIndexesEmbeddedTest Error Message: Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_AC20D7B067BC42E7-001\tempDir-005: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_AC20D7B067BC42E7-001\tempDir-005 Stack Trace: java.io.IOException: Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_AC20D7B067BC42E7-001\tempDir-005: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_AC20D7B067BC42E7-001\tempDir-005 at __randomizedtesting.SeedInfo.seed([AC20D7B067BC42E7]:0) at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329) at org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216) at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43) 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:368) at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 13194 lines...] [junit4] Suite: org.apache.solr.update.processor.AtomicUpdateProcessorFactoryTest [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.AtomicUpdateProcessorFactoryTest_A80656A6D581D49E-001\init-core-data-001 [junit4] 2> 2577188 WARN (SUITE-AtomicUpdateProcessorFactoryTest-seed#[A80656A6D581D49E]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=7 numCloses=7 [junit4] 2> 2577189 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[A80656A6D581D49E]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 2577192 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[A80656A6D581D49E]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) [junit4] 2> 2577192 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[A80656A6D581D49E]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 2577193 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[A80656A6D581D49E]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 2577194 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[A80656A6D581D49E]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 2577236 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[A80656A6D581D49E]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 2577268 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[A80656A6D581D49E]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test [junit4] 2> 2577346 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[A80656A6D581D49E]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 2577419 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[A80656A6D581D49E]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c4decf [junit4] 2> 2577421 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[A80656A6D581D49E]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c4decf [junit4] 2> 2577421 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[A80656A6D581D49E]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c4decf [junit4] 2> 2577423 INFO (coreLoadExecutor-12425-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 2577469 INFO (coreLoadExecutor-12425-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 2577483 INFO (coreLoadExecutor-12425-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 2577557 INFO (coreLoadExecutor-12425-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 2577566 INFO (coreLoadExecutor-12425-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1, trusted=true [junit4] 2> 2577567 INFO (coreLoadExecutor-12425-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c4decf [junit4] 2> 2577567 INFO (coreLoadExecutor-12425-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 2577567 INFO (coreLoadExecutor-12425-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.AtomicUpdateProcessorFactoryTest_A80656A6D581D49E-001\init-core-data-001\] [junit4] 2> 2577570 INFO (coreLoadExecutor-12425-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 2577646 INFO (coreLoadExecutor-12425-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2577646 INFO (coreLoadExecutor-12425-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2577647 INFO (coreLoadExecutor-12425-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2577647 INFO (coreLoadExecutor-12425-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2577648 INFO (coreLoadExecutor-12425-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=11, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=89.673828125, floorSegmentMB=1.3388671875, forceMergeDeletesPctAllowed=23.286545196238766, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1624947876006469 [junit4] 2> 2577649 INFO (coreLoadExecutor-12425-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1caa21f[collection1] main] [junit4] 2> 2577650 INFO (coreLoadExecutor-12425-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 2577655 INFO (coreLoadExecutor-12425-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 2577680 INFO (coreLoadExecutor-12425-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 2577680 INFO (coreLoadExecutor-12425-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1574285297064083456 [junit4] 2> 2577682 INFO (searcherExecutor-12426-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 2577682 INFO (searcherExecutor-12426-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 2577682 INFO (searcherExecutor-12426-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 2577682 INFO (searcherExecutor-12426-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 2577682 INFO (searcherExecutor-12426-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 2577683 INFO (searcherExecutor-12426-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 2577683 INFO (searcherExecutor-12426-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 2577683 INFO (searcherExecutor-12426-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 2577683 INFO (searcherExecutor-12426-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 2577684 INFO (searcherExecutor-12426-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1caa21f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2577685 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[A80656A6D581D49E]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 2577692 INFO (TEST-AtomicUpdateProcessorFactoryTest.testWrongAtomicOpPassed-seed#[A80656A6D581D49E]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWrongAtomicOpPassed [junit4] 2> 2577696 INFO (TEST-AtomicUpdateProcessorFactoryTest.testWrongAtomicOpPassed-seed#[A80656A6D581D49E]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testWrongAtomicOpPassed [junit4] 2> 2577704 INFO (TEST-AtomicUpdateProcessorFactoryTest.testMultipleThreads-seed#[A80656A6D581D49E]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMultipleThreads [junit4] 2> 2577704 INFO (TEST-AtomicUpdateProcessorFactoryTest.testMultipleThreads-seed#[A80656A6D581D49E]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 2577704 INFO (TEST-AtomicUpdateProcessorFactoryTest.testMultipleThreads-seed#[A80656A6D581D49E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@ce954d[collection1] realtime] [junit4] 2> 2577705 INFO (TEST-AtomicUpdateProcessorFactoryTest.testMultipleThreads-seed#[A80656A6D581D49E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:* (-9223372036854775807)} 0 0 [junit4] 2> 2577720 WARN (Thread-6183) [ ] o.a.s.u.p.AtomicUpdateProcessorFactory Atomic update failed due to version conflict for 10 expected=1574285297102880768 actual=1574285297104977920Retrying with new version .... (1) [junit4] 2> 2577720 WARN (Thread-6182) [ ] o.a.s.u.p.AtomicUpdateProcessorFactory Atomic update failed due to version conflict for 10 expected=1574285297102880768 actual=1574285297104977920Retrying with new version .... (1) [junit4] 2> 2577722 WARN (Thread-6184) [ ] o.a.s.u.p.AtomicUpdateProcessorFactory Atomic update failed due to version conflict for 10 expected=1574285297100783616 actual=1574285297106026496Retrying with new version .... (1) [junit4] 2> 2577720 WARN (Thread-6181) [ ] o.a.s.u.p.AtomicUpdateProcessorFactory Atomic update failed due to version conflict for 10 expected=1574285297102880768 actual=1574285297104977920Retrying with new version .... (1) [junit4] 2> 2577720 WARN (Thread-6180) [ ] o.a.s.u.p.AtomicUpdateProcessorFactory Atomic update failed due to version conflict for 10 expected=1574285297102880768 actual=1574285297104977920Retrying with new version .... (1) [junit4] 2> 2577726 WARN (Thread-6186) [ ] o.a.s.u.p.AtomicUpdateProcessorFactory Atomic update failed due to version conflict for 10 expected=1574285297100783616 actual=1574285297108123648Retrying with new version .... (1) [junit4] 2> 2577727 WARN (Thread-6180) [ ] o.a.s.u.p.AtomicUpdateProcessorFactory Atomic update failed due to version conflict for 10 expected=1574285297108123648 actual=1574285297112317952Retrying with new version .... (2) [junit4] 2> 2577727 WARN (Thread-6181) [ ] o.a.s.u.p.AtomicUpdateProcessorFactory Atomic update failed due to version conflict for 10 expected=1574285297108123648 actual=1574285297113366528Retrying with new version .... (2) [junit4] 2> 2577729 WARN (Thread-6184) [ ] o.a.s.u.p.AtomicUpdateProcessorFactory Atomic update failed due to version conflict for 10 expected=1574285297106026496 actual=1574285297113366529Retrying with new version .... (2) [junit4] 2> 2577730 INFO (TEST-AtomicUpdateProcessorFactoryTest.testMultipleThreads-seed#[A80656A6D581D49E]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1574285297116512256,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 2577730 INFO (TEST-AtomicUpdateProcessorFactoryTest.testMultipleThreads-seed#[A80656A6D581D49E]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1d34242 commitCommandVersion:1574285297116512256 [junit4] 2> 2577747 INFO (TEST-AtomicUpdateProcessorFactoryTest.testMultipleThreads-seed#[A80656A6D581D49E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@9fa8d7[collection1] main] [junit4] 2> 2577749 INFO (searcherExecutor-12426-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@9fa8d7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C10/9:delGen=1)))} [junit4] 2> 2577749 INFO (TEST-AtomicUpdateProcessorFactoryTest.testMultipleThreads-seed#[A80656A6D581D49E]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 2577749 INFO (TEST-AtomicUpdateProcessorFactoryTest.testMultipleThreads-seed#[A80656A6D581D49E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 18 [junit4] 2> 2577754 INFO (TEST-AtomicUpdateProcessorFactoryTest.testMultipleThreads-seed#[A80656A6D581D49E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 2577755 INFO (TEST-AtomicUpdateProcessorFactoryTest.testMultipleThreads-seed#[A80656A6D581D49E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=cat:xplft9hpy1lmcacbj2p8+f6hz7pqpay27oyfr8dw9+cp0p3pwu05bc4fhh06fk+odx6os6qyv6qwv9tfltz+sk6p1wn6czquxbylz5nk&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 2577756 INFO (TEST-AtomicUpdateProcessorFactoryTest.testMultipleThreads-seed#[A80656A6D581D49E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=int_i:17&wt=xml} hits=0 status=0 QTime=0 [junit4] 2> 2577756 ERROR (TEST-AtomicUpdateProcessorFactoryTest.testMultipleThreads-seed#[A80656A6D581D49E]) [ ] o.a.s.SolrTestCaseJ4 REQUEST FAILED: xpath=//result[@numFound=1] [junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?> [junit4] 2> <response> [junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result> [junit4] 2> </response> [junit4] 2> [junit4] 2> request was:q=int_i:17&wt=xml [junit4] 2> 2577757 ERROR (TEST-AtomicUpdateProcessorFactoryTest.testMultipleThreads-seed#[A80656A6D581D49E]) [ ] o.a.s.SolrTestCaseJ4 REQUEST FAILED: q=int_i:17&wt=xml:java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1] [junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?> [junit4] 2> <response> [junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result> [junit4] 2> </response> [junit4] 2> [junit4] 2> request was:q=int_i:17&wt=xml [junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:879) [junit4] 2> at org.apache.solr.update.processor.AtomicUpdateProcessorFactoryTest.testMultipleThreads(AtomicUpdateProcessorFactoryTest.java:264) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> [junit4] 2> 2577757 INFO (TEST-AtomicUpdateProcessorFactoryTest.testMultipleThreads-seed#[A80656A6D581D49E]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMultipleThreads [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=AtomicUpdateProcessorFactoryTest -Dtests.method=testMultipleThreads -Dtests.seed=A80656A6D581D49E -Dtests.slow=true -Dtests.locale=es-BO -Dtests.timezone=America/Fort_Wayne -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] ERROR 0.06s J1 | AtomicUpdateProcessorFactoryTest.testMultipleThreads <<< [junit4] > Throwable #1: java.lang.RuntimeException: Exception during query [junit4] > at __randomizedtesting.SeedInfo.seed([A80656A6D581D49E:8434212B5945A21A]:0) [junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:886) [junit4] > at org.apache.solr.update.processor.AtomicUpdateProcessorFactoryTest.testMultipleThreads(AtomicUpdateProcessorFactoryTest.java:264) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1] [junit4] > xml response was: <?xml version="1.0" encoding="UTF-8"?> [junit4] > <response> [junit4] > <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result> [junit4] > </response> [junit4] > request was:q=int_i:17&wt=xml [junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:879) [junit4] > ... 40 more [junit4] 2> 2577766 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBasics [junit4] 2> 2577769 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1574285297157406720,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 2577769 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1d34242 commitCommandVersion:1574285297157406720 [junit4] 2> 2577787 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@187dbad[collection1] main] [junit4] 2> 2577805 INFO (searcherExecutor-12426-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@187dbad[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C10/9:delGen=1) Uninverting(_1(8.0.0):C1)))} [junit4] 2> 2577807 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 2577808 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 38 [junit4] 2> 2577810 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&wt=xml} hits=1 status=0 QTime=1 [junit4] 2> 2577810 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=cat:human&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 2577811 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=title:Mr&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 2577811 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=count_i:20&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 2577811 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=name_s:Virat&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 2577812 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=multiDefault:Delhi&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 2577814 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1574285297204592640,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 2577814 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1d34242 commitCommandVersion:1574285297204592640 [junit4] 2> 2577828 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@91357[collection1] main] [junit4] 2> 2577830 INFO (searcherExecutor-12426-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@91357[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C10/9:delGen=1) Uninverting(_2(8.0.0):C1)))} [junit4] 2> 2577830 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 2577830 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 16 [junit4] 2> 2577831 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 2577831 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=cat:human&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 2577831 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=cat:animal&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 2577832 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=title:Mr&wt=xml} hits=0 status=0 QTime=0 [junit4] 2> 2577832 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=title:Dr&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 2577832 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=count_i:20&wt=xml} hits=0 status=0 QTime=0 [junit4] 2> 2577838 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=count_i:40&wt=xml} hits=1 status=0 QTime=5 [junit4] 2> 2577839 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=name_s:Virat&wt=xml} hits=0 status=0 QTime=0 [junit4] 2> 2577839 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=multiDefault:Delhi&wt=xml} hits=0 status=0 QTime=0 [junit4] 2> 2577839 INFO (TEST-AtomicUpdateProcessorFactoryTest.testBasics-seed#[A80656A6D581D49E]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testBasics [junit4] 2> 2577846 INFO (TEST-AtomicUpdateProcessorFactoryTest.testNoUniqueIdPassed-seed#[A80656A6D581D49E]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNoUniqueIdPassed [junit4] 2> 2577846 INFO (TEST-AtomicUpdateProcessorFactoryTest.testNoUniqueIdPassed-seed#[A80656A6D581D49E]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testNoUniqueIdPassed [junit4] 2> 2577847 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[A80656A6D581D49E]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 2577847 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[A80656A6D581D49E]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=4428074 [junit4] 2> 2577847 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[A80656A6D581D49E]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 2577848 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[A80656A6D581D49E]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 2577849 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[A80656A6D581D49E]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 2577849 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[A80656A6D581D49E]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2577851 INFO (coreCloseExecutor-12431-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@109d472 [junit4] 2> 2577854 INFO (coreCloseExecutor-12431-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=17421426 [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.AtomicUpdateProcessorFactoryTest_A80656A6D581D49E-001 [junit4] 2> Jul 29, 2017 7:10:54 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {name_s=FSTOrd50, title_stringNoNorms=PostingsFormat(name=Memory), title_stemmed=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), multiDefault=PostingsFormat(name=Memory), cat=PostingsFormat(name=Memory), id=FSTOrd50, text=PostingsFormat(name=LuceneVarGapDocFreqInterval), title=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), title_lettertok=FSTOrd50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Memory), _version_=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Asserting), intDvoDefault=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=360, maxMBSortInHeap=6.72558893642625, sim=RandomSimilarity(queryNorm=false): {}, locale=es-BO, timezone=America/Fort_Wayne [junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_141 (32-bit)/cpus=3,threads=1,free=188938872,total=530055168 [junit4] 2> NOTE: All tests run in this JVM: [SpatialHeatmapFacetsTest, TestScoreJoinQPNoScore, ForceLeaderTest, StatelessScriptUpdateProcessorFactoryTest, TestLeaderInitiatedRecoveryThread, TestPostingsSolrHighlighter, TestTestInjection, TestSolr4Spatial2, DocExpirationUpdateProcessorFactoryTest, DistributedFacetPivotLargeTest, BigEndianAscendingWordSerializerTest, BlockJoinFacetDistribTest, TestLegacyFieldReuse, CollectionReloadTest, TestFastOutputStream, TestStressInPlaceUpdates, TestValueSourceCache, HLLSerializationTest, DirectoryFactoryTest, TestReload, FileUtilsTest, TestLockTree, TestDocBasedVersionConstraints, TestCollationField, TestSubQueryTransformerCrossCore, CdcrUpdateLogTest, TestJettySolrRunner, SpellCheckCollatorWithCollapseTest, TestRangeQuery, PreAnalyzedFieldManagedSchemaCloudTest, ExplicitHLLTest, TestComplexPhraseQParserPlugin, SpellCheckCollatorTest, VersionInfoTest, ChangedSchemaMergeTest, TestImplicitCoreProperties, DistribJoinFromCollectionTest, SSLMigrationTest, CursorMarkTest, TestPayloadScoreQParserPlugin, TestFieldTypeResource, TestJsonFacetRefinement, TestDFISimilarityFactory, ShardSplitTest, TermVectorComponentDistributedTest, TestRuleBasedAuthorizationPlugin, SecurityConfHandlerTest, EnumFieldTest, MultiThreadedOCPTest, TestCoreAdminApis, MoveReplicaHDFSUlogDirTest, MissingSegmentRecoveryTest, BlockJoinFacetSimpleTest, TestDistribDocBasedVersion, PluginInfoTest, ActionThrottleTest, TestCollapseQParserPlugin, QueryEqualityTest, TestManagedSchema, BlockCacheTest, DistributedVersionInfoTest, TestHdfsUpdateLog, TestRestManager, TestUnifiedSolrHighlighter, JsonLoaderTest, CdcrReplicationHandlerTest, ClassificationUpdateProcessorFactoryTest, TestSolrJ, HdfsDirectoryFactoryTest, DocumentAnalysisRequestHandlerTest, TestBlendedInfixSuggestions, ImplicitSnitchTest, TestPullReplica, TestRequestForwarding, SOLR749Test, RulesTest, TestTrie, SystemInfoHandlerTest, LukeRequestHandlerTest, HdfsChaosMonkeyNothingIsSafeTest, ShardRoutingCustomTest, TestSearchPerf, SolrXmlInZkTest, TestRandomCollapseQParserPlugin, TestDelegationWithHadoopAuth, UninvertDocValuesMergePolicyTest, TestLegacyFieldCache, DeleteNodeTest, TestQueryUtils, ConfigSetsAPITest, TestDocumentBuilder, MBeansHandlerTest, TestUseDocValuesAsStored, StatsReloadRaceTest, TestIndexSearcher, TestFreeTextSuggestions, TestPartialUpdateDeduplication, SearchHandlerTest, UUIDUpdateProcessorFallbackTest, HdfsRecoverLeaseTest, TestFiltering, TestSolrXml, TestShortCircuitedRequests, TestReloadAndDeleteDocs, TestExactSharedStatsCache, StatsComponentTest, SharedFSAutoReplicaFailoverUtilsTest, TestCoreDiscovery, ParsingFieldUpdateProcessorsTest, TestCollectionAPIs, SuggestComponentTest, TestSolrCloudWithHadoopAuthPlugin, DistribCursorPagingTest, LeaderInitiatedRecoveryOnShardRestartTest, SolrPluginUtilsTest, PeerSyncWithIndexFingerprintCachingTest, TermsComponentTest, SchemaVersionSpecificBehaviorTest, RestartWhileUpdatingTest, TestInPlaceUpdatesStandalone, DistributedIntervalFacetingTest, CreateCollectionCleanupTest, TestSolrIndexConfig, TestStressLucene, TestRTimerTree, DOMUtilTest, TestXmlQParser, JSONWriterTest, BadIndexSchemaTest, TestCloudJSONFacetJoinDomain, ReplaceNodeTest, TestNumericTokenStream, DistributedQueryElevationComponentTest, TestStressUserVersions, OverseerTaskQueueTest, SampleTest, SolrCmdDistributorTest, CollectionTooManyReplicasTest, CleanupOldIndexTest, ResponseHeaderTest, TestCSVLoader, TestFieldCacheSort, MoreLikeThisHandlerTest, TestSuggestSpellingConverter, TestElisionMultitermQuery, SolrCLIZkUtilsTest, TestConfigSetsAPIExclusivity, TestInitParams, TestFieldCacheVsDocValues, TestJoin, SpatialRPTFieldTypeTest, TestChildDocTransformer, SolrCoreMetricManagerTest, SolrCloudReportersTest, TestRawTransformer, TestCloudPseudoReturnFields, ShowFileRequestHandlerTest, BufferStoreTest, TestSystemIdResolver, PreAnalyzedFieldTest, TestDocSet, TestFastWriter, TestPerFieldSimilarity, IndexSchemaRuntimeFieldTest, DeleteShardTest, TestConfigSets, TestRandomFlRTGCloud, HdfsBasicDistributedZk2Test, TestOnReconnectListenerSupport, TestRTGBase, TestMaxScoreQueryParser, TestCopyFieldCollectionResource, TestRandomFaceting, CoreMergeIndexesAdminHandlerTest, SynonymTokenizerTest, DistributedFacetPivotSmallAdvancedTest, CdcrVersionReplicationTest, PingRequestHandlerTest, AutoScalingHandlerTest, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest, OpenCloseCoreStressTest, OverseerTest, BasicZkTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, LeaderElectionTest, ZkSolrClientTest, TestZkChroot, TestRandomDVFaceting, AutoCommitTest, ZkControllerTest, TestRealTimeGet, TestStressReorder, TestStressVersions, HardAutoCommitTest, SimpleFacetsTest, TestSolr4Spatial, SpellCheckComponentTest, PeerSyncTest, AnalysisAfterCoreReloadTest, SuggesterFSTTest, SolrRequestParserTest, SuggesterTest, NoCacheHeaderTest, SolrCoreCheckLockOnStartupTest, FieldMutatingUpdateProcessorTest, TestRemoteStreaming, TestSolrDeletionPolicy1, DebugComponentTest, IndexBasedSpellCheckerTest, DirectSolrSpellCheckerTest, TestQueryTypes, DocumentBuilderTest, PathHierarchyTokenizerFactoryTest, FieldAnalysisRequestHandlerTest, FastVectorHighlighterTest, RegexBoostProcessorTest, TestJmxIntegration, TestCSVResponseWriter, BinaryUpdateRequestHandlerTest, TestComponentsName, UpdateParamsTest, CopyFieldTest, BadComponentTest, TestMergePolicyConfig, ExternalFileFieldSortTest, TestPhraseSuggestions, TestXIncludeConfig, TestSweetSpotSimilarityFactory, TestLMDirichletSimilarityFactory, TestIBSimilarityFactory, OpenExchangeRatesOrgProviderTest, SpellingQueryConverterTest, TestLRUCache, CursorPagingTest, TestDistributedMissingSort, TestEmbeddedSolrServerSchemaAPI, AssignTest, AsyncCallRequestStatusResponseTest, CdcrBootstrapTest, CollectionsAPISolrJTest, LeaderFailoverAfterPartitionTest, MigrateRouteKeyTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverseerCollectionConfigSetProcessorTest, OverseerModifyCollectionTest, RecoveryAfterSoftCommitTest, TestCloudDeleteByQuery, TestClusterProperties, TestCollectionAPI, TestConfigSetsAPIZkFailure, TestCryptoKeys, TestDeleteCollectionOnDownNodes, TestMiniSolrCloudClusterSSL, TestRebalanceLeaders, TestSSLRandomization, TestSolrCloudWithDelegationTokens, TestSolrCloudWithKerberosAlt, TestTlogReplica, TestConfigReload, TestSQLHandler, DistributedFacetPivotSmallTest, DistributedQueryComponentCustomSortTest, DistributedSuggestComponentTest, TestExpandComponent, HighlighterMaxOffsetTest, TestLegacyTerms, TestNumericRangeQuery32, JvmMetricsTest, MetricsConfigTest, SolrMetricReporterTest, SolrMetricsIntegrationTest, SolrGraphiteReporterTest, SolrSlf4jReporterTest, TestMacroExpander, SmileWriterTest, TestExportWriter, TestRawResponseWriter, TestManagedResource, TestManagedResourceStorage, TestDynamicFieldCollectionResource, TestDynamicFieldResource, TestFieldCollectionResource, TestFieldResource, TestFieldTypeCollectionResource, TestSchemaNameResource, TestSchemaResource, TestSchemaSimilarityResource, TestSchemaVersionResource, TestUniqueKeyFieldResource, TestManagedSynonymFilterFactory, BadCopyFieldTest, BooleanFieldTest, CurrencyFieldTypeTest, ManagedSchemaRoundRobinCloudTest, TestCloudManagedSchema, TestManagedSchemaThreadSafety, TestPointFields, AnalyticsQueryTest, MergeStrategyTest, RankQueryTest, SortSpecParsingTest, TestAddFieldRealTimeGet, TestComplexPhraseLeadingWildcard, TestFieldSortValues, TestGraphTermsQParserPlugin, TestHashQParserPlugin, TestInitQParser, TestLegacyNumericRangeQueryBuilder, TestMissingGroups, TestMultiWordSynonyms, TestReRankQParserPlugin, TestSearcherReuse, TestSmileRequest, TestMinMaxOnMultiValuedField, TestSortByMinMaxFunction, BlockJoinFacetRandomTest, GraphQueryTest, TestCloudNestedDocsSort, TestJsonRequest, CloudMLTQParserTest, SimpleMLTQParserTest, TestPerFieldSimilarityWithDefaultOverride, TestDefaultStatsCache, TestExactStatsCache, TestLRUStatsCache, TestAuthorizationFramework, TestExceedMaxTermLength, AtomicUpdateProcessorFactoryTest] [junit4] Completed [714/726 (1!)] on J1 in 0.95s, 4 tests, 1 error <<< FAILURES! [...truncated 478 lines...] [junit4] Suite: org.apache.solr.client.solrj.embedded.MergeIndexesEmbeddedTest [junit4] 2> 9162 INFO (SUITE-MergeIndexesEmbeddedTest-seed#[AC20D7B067BC42E7]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_AC20D7B067BC42E7-001\init-core-data-001 [junit4] 2> 9163 WARN (SUITE-MergeIndexesEmbeddedTest-seed#[AC20D7B067BC42E7]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=8 numCloses=8 [junit4] 2> 9164 INFO (SUITE-MergeIndexesEmbeddedTest-seed#[AC20D7B067BC42E7]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 9167 INFO (SUITE-MergeIndexesEmbeddedTest-seed#[AC20D7B067BC42E7]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) [junit4] 2> 9169 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[AC20D7B067BC42E7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMergeMultipleRequest [junit4] 2> 9176 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\solr.xml [junit4] 2> 9321 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.CorePropertiesLocator Found 2 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore [junit4] 2> 9323 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [core0, core1] [junit4] 2> 9349 INFO (coreLoadExecutor-19-thread-1) [ x:core0] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 9353 INFO (coreLoadExecutor-19-thread-2) [ x:core1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 9367 INFO (coreLoadExecutor-19-thread-1) [ x:core0] o.a.s.s.IndexSchema [core0] Schema name=example core zero [junit4] 2> 9368 INFO (coreLoadExecutor-19-thread-2) [ x:core1] o.a.s.s.IndexSchema [core1] Schema name=example core one [junit4] 2> 9371 INFO (coreLoadExecutor-19-thread-2) [ x:core1] o.a.s.s.IndexSchema Loaded schema example core one/1.1 with uniqueid field id [junit4] 2> 9371 INFO (coreLoadExecutor-19-thread-2) [ x:core1] o.a.s.c.CoreContainer Creating SolrCore 'core1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core1, trusted=true [junit4] 2> 9377 INFO (coreLoadExecutor-19-thread-1) [ x:core0] o.a.s.s.IndexSchema Loaded schema example core zero/1.1 with uniqueid field id [junit4] 2> 9377 INFO (coreLoadExecutor-19-thread-1) [ x:core0] o.a.s.c.CoreContainer Creating SolrCore 'core0' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core0, trusted=true [junit4] 2> 9392 INFO (coreLoadExecutor-19-thread-2) [ x:core1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 9392 INFO (coreLoadExecutor-19-thread-2) [ x:core1] o.a.s.c.SolrCore [[core1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_AC20D7B067BC42E7-001\tempDir-002\] [junit4] 2> 9394 INFO (coreLoadExecutor-19-thread-1) [ x:core0] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 9394 INFO (coreLoadExecutor-19-thread-1) [ x:core0] o.a.s.c.SolrCore [[core0] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core0], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_AC20D7B067BC42E7-001\tempDir-001\] [junit4] 2> 9492 INFO (coreLoadExecutor-19-thread-1) [ x:core0] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 9495 INFO (coreLoadExecutor-19-thread-1) [ x:core0] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_AC20D7B067BC42E7-001\tempDir-001 defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 9497 INFO (coreLoadExecutor-19-thread-1) [ x:core0] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 9497 INFO (coreLoadExecutor-19-thread-1) [ x:core0] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 9505 INFO (coreLoadExecutor-19-thread-2) [ x:core1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 9505 INFO (coreLoadExecutor-19-thread-2) [ x:core1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_AC20D7B067BC42E7-001\tempDir-002 defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 9506 INFO (coreLoadExecutor-19-thread-2) [ x:core1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 9506 INFO (coreLoadExecutor-19-thread-2) [ x:core1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 9511 INFO (coreLoadExecutor-19-thread-1) [ x:core0] o.a.s.s.SolrIndexSearcher Opening [Searcher@62b214[core0] main] [junit4] 2> 9511 WARN (coreLoadExecutor-19-thread-1) [ x:core0] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core0\conf; switching to use InMemory storage instead. [junit4] 2> 9513 INFO (coreLoadExecutor-19-thread-2) [ x:core1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a2f2ac[core1] main] [junit4] 2> 9513 WARN (coreLoadExecutor-19-thread-2) [ x:core1] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core1\conf; switching to use InMemory storage instead. [junit4] 2> 9521 INFO (coreLoadExecutor-19-thread-1) [ x:core0] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 9526 INFO (coreLoadExecutor-19-thread-2) [ x:core1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 9527 INFO (searcherExecutor-21-thread-1-processing-x:core0) [ x:core0] o.a.s.c.SolrCore [core0] Registered new searcher Searcher@62b214[core0] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 9527 INFO (coreLoadExecutor-19-thread-1) [ x:core0] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1574285360314187776 [junit4] 2> 9529 INFO (searcherExecutor-20-thread-1-processing-x:core1) [ x:core1] o.a.s.c.SolrCore [core1] Registered new searcher Searcher@1a2f2ac[core1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 9529 INFO (coreLoadExecutor-19-thread-2) [ x:core1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1574285360316284928 [junit4] 2> 9530 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 9530 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.s.MergeIndexesExampleTestBase CORES=org.apache.solr.core.CoreContainer@19b5235 : [core0, core1] [junit4] 2> 9534 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[AC20D7B067BC42E7]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMergeMultipleRequest [junit4] 2> 9534 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=26956341 [junit4] 2> 9534 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[AC20D7B067BC42E7]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 9534 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[AC20D7B067BC42E7]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 9534 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[AC20D7B067BC42E7]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 9535 INFO (coreCloseExecutor-30-thread-1) [ x:core0] o.a.s.c.SolrCore [core0] CLOSING SolrCore org.apache.solr.core.SolrCore@d09ed [junit4] 2> 9537 INFO (coreCloseExecutor-30-thread-2) [ x:core1] o.a.s.c.SolrCore [core1] CLOSING SolrCore org.apache.solr.core.SolrCore@11b4d5e [junit4] 2> 9539 INFO (coreCloseExecutor-30-thread-1) [ x:core0] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.core0, tag=854509 [junit4] 2> 9542 INFO (coreCloseExecutor-30-thread-2) [ x:core1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.core1, tag=18566494 [junit4] 2> 9560 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMergeIndexesByCoreName [junit4] 2> 9563 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\solr.xml [junit4] 2> 9649 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.CorePropertiesLocator Found 2 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore [junit4] 2> 9649 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [core0, core1] [junit4] 2> 9676 INFO (coreLoadExecutor-37-thread-1) [ x:core0] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 9678 INFO (coreLoadExecutor-37-thread-2) [ x:core1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 9685 INFO (coreLoadExecutor-37-thread-1) [ x:core0] o.a.s.s.IndexSchema [core0] Schema name=example core zero [junit4] 2> 9687 INFO (coreLoadExecutor-37-thread-2) [ x:core1] o.a.s.s.IndexSchema [core1] Schema name=example core one [junit4] 2> 9690 INFO (coreLoadExecutor-37-thread-1) [ x:core0] o.a.s.s.IndexSchema Loaded schema example core zero/1.1 with uniqueid field id [junit4] 2> 9690 INFO (coreLoadExecutor-37-thread-1) [ x:core0] o.a.s.c.CoreContainer Creating SolrCore 'core0' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core0, trusted=true [junit4] 2> 9690 INFO (coreLoadExecutor-37-thread-1) [ x:core0] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 9690 INFO (coreLoadExecutor-37-thread-1) [ x:core0] o.a.s.c.SolrCore [[core0] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core0], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_AC20D7B067BC42E7-001\tempDir-003\] [junit4] 2> 9690 INFO (coreLoadExecutor-37-thread-2) [ x:core1] o.a.s.s.IndexSchema Loaded schema example core one/1.1 with uniqueid field id [junit4] 2> 9690 INFO (coreLoadExecutor-37-thread-2) [ x:core1] o.a.s.c.CoreContainer Creating SolrCore 'core1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core1, trusted=true [junit4] 2> 9690 INFO (coreLoadExecutor-37-thread-2) [ x:core1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 9690 INFO (coreLoadExecutor-37-thread-2) [ x:core1] o.a.s.c.SolrCore [[core1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_AC20D7B067BC42E7-001\tempDir-004\] [junit4] 2> 9788 INFO (coreLoadExecutor-37-thread-1) [ x:core0] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 9788 INFO (coreLoadExecutor-37-thread-1) [ x:core0] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_AC20D7B067BC42E7-001\tempDir-003 defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 9789 INFO (coreLoadExecutor-37-thread-1) [ x:core0] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 9789 INFO (coreLoadExecutor-37-thread-1) [ x:core0] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 9792 INFO (coreLoadExecutor-37-thread-1) [ x:core0] o.a.s.s.SolrIndexSearcher Opening [Searcher@979556[core0] main] [junit4] 2> 9793 WARN (coreLoadExecutor-37-thread-1) [ x:core0] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core0\conf; switching to use InMemory storage instead. [junit4] 2> 9793 INFO (coreLoadExecutor-37-thread-1) [ x:core0] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 9793 INFO (coreLoadExecutor-37-thread-1) [ x:core0] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1574285360593108992 [junit4] 2> 9828 INFO (coreLoadExecutor-37-thread-2) [ x:core1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 9828 INFO (coreLoadExecutor-37-thread-2) [ x:core1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_AC20D7B067BC42E7-001\tempDir-004 defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 9829 INFO (coreLoadExecutor-37-thread-2) [ x:core1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 9829 INFO (coreLoadExecutor-37-thread-2) [ x:core1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 9831 INFO (coreLoadExecutor-37-thread-2) [ x:core1] o.a.s.s.SolrIndexSearcher Opening [Searcher@171b3e7[core1] main] [junit4] 2> 9831 WARN (coreLoadExecutor-37-thread-2) [ x:core1] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core1\conf; switching to use InMemory storage instead. [junit4] 2> 9833 INFO (coreLoadExecutor-37-thread-2) [ x:core1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 9833 INFO (coreLoadExecutor-37-thread-2) [ x:core1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1574285360635052032 [junit4] 2> 9834 INFO (searcherExecutor-39-thread-1-processing-x:core1) [ x:core1] o.a.s.c.SolrCore [core1] Registered new searcher Searcher@171b3e7[core1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 9834 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 9834 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.s.MergeIndexesExampleTestBase CORES=org.apache.solr.core.CoreContainer@dc5945 : [core0, core1] [junit4] 2> 9838 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1574285360640294912,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 9838 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 9838 INFO (searcherExecutor-38-thread-1-processing-x:core0) [ x:core0] o.a.s.c.SolrCore [core0] Registered new searcher Searcher@979556[core0] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 9839 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 9839 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [core0] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true}{deleteByQuery=*:* (-1574285360637149184),commit=} 0 4 [junit4] 2> 9842 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1574285360644489216,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 9842 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 9843 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 9843 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [core1] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true}{deleteByQuery=*:* (-1574285360642392064),commit=} 0 2 [junit4] 2> 9908 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1574285360712646656,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 9908 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@9c1c99 commitCommandVersion:1574285360712646656 [junit4] 2> 10095 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1704d33[core0] main] [junit4] 2> 10096 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 10096 INFO (searcherExecutor-38-thread-1) [ ] o.a.s.c.SolrCore [core0] Registered new searcher Searcher@1704d33[core0] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1)))} [junit4] 2> 10097 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [core0] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true}{add=[AAA (1574285360645537792)],commit=} 0 253 [junit4] 2> 10104 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1574285360919216128,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 10104 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e59fd5 commitCommandVersion:1574285360919216128 [junit4] 2> 10156 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b5ca57[core1] main] [junit4] 2> 10157 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 10157 INFO (searcherExecutor-39-thread-1) [ ] o.a.s.c.SolrCore [core1] Registered new searcher Searcher@1b5ca57[core1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1)))} [junit4] 2> 10157 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [core1] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true}{add=[BBB (1574285360912924672)],commit=} 0 60 [junit4] 2> 10195 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:AAA} hits=1 status=0 QTime=36 [junit4] 2> 10196 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.S.Request [core1] webapp=null path=/select params={q=id:AAA} hits=0 status=0 QTime=0 [junit4] 2> 10196 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:AAA} hits=1 status=0 QTime=0 [junit4] 2> 10197 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:BBB} hits=0 status=0 QTime=0 [junit4] 2> 10198 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.S.Request [core1] webapp=null path=/select params={q=id:AAA} hits=0 status=0 QTime=0 [junit4] 2> 10198 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.S.Request [core1] webapp=null path=/select params={q=id:BBB} hits=1 status=0 QTime=0 [junit4] 2> 10208 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 start mergeIndexes{SimpleFSDirectory@C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_AC20D7B067BC42E7-001\tempDir-004\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c2ee3d} [junit4] 2> 10441 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 end_mergeIndexes [junit4] 2> 10442 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1574285361273634816,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 10442 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@9c1c99 commitCommandVersion:1574285361273634816 [junit4] 2> 10467 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1fed206[core0] main] [junit4] 2> 10467 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 10468 INFO (searcherExecutor-38-thread-1) [ ] o.a.s.c.SolrCore [core0] Registered new searcher Searcher@1fed206[core0] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1) Uninverting(_1(8.0.0):C1)))} [junit4] 2> 10468 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [core0] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true}{commit=} 0 26 [junit4] 2> 10468 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:AAA} hits=1 status=0 QTime=0 [junit4] 2> 10469 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:BBB} hits=1 status=0 QTime=0 [junit4] 2> 10469 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMergeIndexesByCoreName [junit4] 2> 10469 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=14440773 [junit4] 2> 10469 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 10469 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 10469 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 10470 INFO (coreCloseExecutor-48-thread-1) [ x:core0] o.a.s.c.SolrCore [core0] CLOSING SolrCore org.apache.solr.core.SolrCore@12b30ff [junit4] 2> 10470 INFO (coreCloseExecutor-48-thread-2) [ x:core1] o.a.s.c.SolrCore [core1] CLOSING SolrCore org.apache.solr.core.SolrCore@1846ae1 [junit4] 2> 10475 INFO (coreCloseExecutor-48-thread-1) [ x:core0] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.core0, tag=19607807 [junit4] 2> 10477 INFO (coreCloseExecutor-48-thread-2) [ x:core1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.core1, tag=25455329 [junit4] 2> 10480 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMergeIndexesByDirName [junit4] 2> 10484 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\solr.xml [junit4] 2> 10556 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.CorePropertiesLocator Found 2 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore [junit4] 2> 10556 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [core0, core1] [junit4] 2> 10582 INFO (coreLoadExecutor-55-thread-1) [ x:core0] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 10583 INFO (coreLoadExecutor-55-thread-2) [ x:core1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 10592 INFO (coreLoadExecutor-55-thread-2) [ x:core1] o.a.s.s.IndexSchema [core1] Schema name=example core one [junit4] 2> 10592 INFO (coreLoadExecutor-55-thread-1) [ x:core0] o.a.s.s.IndexSchema [core0] Schema name=example core zero [junit4] 2> 10595 INFO (coreLoadExecutor-55-thread-2) [ x:core1] o.a.s.s.IndexSchema Loaded schema example core one/1.1 with uniqueid field id [junit4] 2> 10595 INFO (coreLoadExecutor-55-thread-2) [ x:core1] o.a.s.c.CoreContainer Creating SolrCore 'core1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core1, trusted=true [junit4] 2> 10595 INFO (coreLoadExecutor-55-thread-1) [ x:core0] o.a.s.s.IndexSchema Loaded schema example core zero/1.1 with uniqueid field id [junit4] 2> 10595 INFO (coreLoadExecutor-55-thread-1) [ x:core0] o.a.s.c.CoreContainer Creating SolrCore 'core0' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core0, trusted=true [junit4] 2> 10595 INFO (coreLoadExecutor-55-thread-2) [ x:core1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 10596 INFO (coreLoadExecutor-55-thread-2) [ x:core1] o.a.s.c.SolrCore [[core1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_AC20D7B067BC42E7-001\tempDir-006\] [junit4] 2> 10596 INFO (coreLoadExecutor-55-thread-1) [ x:core0] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 10596 INFO (coreLoadExecutor-55-thread-1) [ x:core0] o.a.s.c.SolrCore [[core0] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core0], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_AC20D7B067BC42E7-001\tempDir-005\] [junit4] 2> 10665 INFO (coreLoadExecutor-55-thread-1) [ x:core0] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 10666 INFO (coreLoadExecutor-55-thread-1) [ x:core0] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_AC20D7B067BC42E7-001\tempDir-005 defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 10667 INFO (coreLoadExecutor-55-thread-1) [ x:core0] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 10667 INFO (coreLoadExecutor-55-thread-1) [ x:core0] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 10669 INFO (coreLoadExecutor-55-thread-1) [ x:core0] o.a.s.s.SolrIndexSearcher Opening [Searcher@58049b[core0] main] [junit4] 2> 10669 WARN (coreLoadExecutor-55-thread-1) [ x:core0] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core0\conf; switching to use InMemory storage instead. [junit4] 2> 10669 INFO (coreLoadExecutor-55-thread-1) [ x:core0] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 10669 INFO (coreLoadExecutor-55-thread-1) [ x:core0] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1574285361511661568 [junit4] 2> 10671 INFO (coreLoadExecutor-55-thread-2) [ x:core1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 10671 INFO (coreLoadExecutor-55-thread-2) [ x:core1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_AC20D7B067BC42E7-001\tempDir-006 defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 10672 INFO (coreLoadExecutor-55-thread-2) [ x:core1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 10672 INFO (coreLoadExecutor-55-thread-2) [ x:core1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 10674 INFO (coreLoadExecutor-55-thread-2) [ x:core1] o.a.s.s.SolrIndexSearcher Opening [Searcher@e50fac[core1] main] [junit4] 2> 10674 WARN (coreLoadExecutor-55-thread-2) [ x:core1] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core1\conf; switching to use InMemory storage instead. [junit4] 2> 10676 INFO (searcherExecutor-57-thread-1-processing-x:core0) [ x:core0] o.a.s.c.SolrCore [core0] Registered new searcher Searcher@58049b[core0] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 10676 INFO (coreLoadExecutor-55-thread-2) [ x:core1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 10677 INFO (searcherExecutor-56-thread-1-processing-x:core1) [ x:core1] o.a.s.c.SolrCore [core1] Registered new searcher Searcher@e50fac[core1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 10677 INFO (coreLoadExecutor-55-thread-2) [ x:core1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1574285361520050176 [junit4] 2> 10678 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 10678 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.s.MergeIndexesExampleTestBase CORES=org.apache.solr.core.CoreContainer@1bb8369 : [core0, core1] [junit4] 2> 10681 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1574285361524244480,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 10681 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 10682 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 10682 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [core0] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true}{deleteByQuery=*:* (-1574285361522147328),commit=} 0 3 [junit4] 2> 10686 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1574285361529487360,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 10686 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 10687 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 10687 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [core1] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true}{deleteByQuery=*:* (-1574285361525293056),commit=} 0 5 [junit4] 2> 10692 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1574285361535778816,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 10692 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1c5115d commitCommandVersion:1574285361535778816 [junit4] 2> 10707 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@be8f86[core0] main] [junit4] 2> 10709 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 10709 INFO (searcherExecutor-57-thread-1) [ ] o.a.s.c.SolrCore [core0] Registered new searcher Searcher@be8f86[core0] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1)))} [junit4] 2> 10710 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [core0] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true}{add=[AAA (1574285361530535936)],commit=} 0 21 [junit4] 2> 10713 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1574285361557798912,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 10713 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@196959a commitCommandVersion:1574285361557798912 [junit4] 2> 10729 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@455eec[core1] main] [junit4] 2> 10730 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 10730 INFO (searcherExecutor-56-thread-1) [ ] o.a.s.c.SolrCore [core1] Registered new searcher Searcher@455eec[core1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1)))} [junit4] 2> 10730 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [core1] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true}{add=[BBB (1574285361554653184)],commit=} 0 21 [junit4] 2> 10731 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:AAA} hits=1 status=0 QTime=0 [junit4] 2> 10731 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.S.Request [core1] webapp=null path=/select params={q=id:AAA} hits=0 status=0 QTime=0 [junit4] 2> 10732 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:AAA} hits=1 status=0 QTime=0 [junit4] 2> 10732 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:BBB} hits=0 status=0 QTime=0 [junit4] 2> 10733 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.S.Request [core1] webapp=null path=/select params={q=id:AAA} hits=0 status=0 QTime=0 [junit4] 2> 10733 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.S.Request [core1] webapp=null path=/select params={q=id:BBB} hits=1 status=0 QTime=0 [junit4] 2> 10739 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 start mergeIndexes{SimpleFSDirectory@C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_AC20D7B067BC42E7-001\tempDir-006\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c2ee3d} [junit4] 2> 10750 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 end_mergeIndexes [junit4] 2> 10751 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1574285361596596224,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 10751 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1c5115d commitCommandVersion:1574285361596596224 [junit4] 2> 10761 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@20564[core0] main] [junit4] 2> 10761 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 10761 INFO (searcherExecutor-57-thread-1) [ ] o.a.s.c.SolrCore [core0] Registered new searcher Searcher@20564[core0] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1) Uninverting(_1(8.0.0):C1)))} [junit4] 2> 10761 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [core0] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true}{commit=} 0 11 [junit4] 2> 10762 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:AAA} hits=1 status=0 QTime=0 [junit4] 2> 10762 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:BBB} hits=1 status=0 QTime=0 [junit4] 2> 10764 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMergeIndexesByDirName [junit4] 2> 10764 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=29066089 [junit4] 2> 10764 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 10764 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 10764 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[AC20D7B067BC42E7]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 10764 INFO (coreCloseExecutor-66-thread-1) [ x:core0] o.a.s.c.SolrCore [core0] CLOSING SolrCore org.apache.solr.core.SolrCore@1983d13 [junit4] 2> 10765 INFO (coreCloseExecutor-66-thread-2) [ x:core1] o.a.s.c.SolrCore [core1] CLOSING SolrCore org.apache.solr.core.SolrCore@31da11 [junit4] 2> 10771 INFO (coreCloseExecutor-66-thread-2) [ x:core1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.core1, tag=3267089 [junit4] 2> 10772 INFO (coreCloseExecutor-66-thread-1) [ x:core0] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.core0, tag=26754323 [junit4] 2> 10773 INFO (SUITE-MergeIndexesEmbeddedTest-seed#[AC20D7B067BC42E7]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {name=PostingsFormat(name=LuceneFixedGap), id=PostingsFormat(name=LuceneFixedGap)}, docValues:{_version_=DocValuesFormat(name=Memory), name=DocValuesFormat(name=Lucene70), id=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=1047, maxMBSortInHeap=7.562935356616791, sim=RandomSimilarity(queryNorm=false): {}, locale=it, timezone=America/Knox_IN [junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_141 (32-bit)/cpus=3,threads=1,free=21633472,total=196083712 [junit4] 2> NOTE: All tests run in this JVM: [UuidEvaluatorTest, ConcatOperationTest, GetByIdTest, TestFastInputStream, MergeIndexesEmbeddedTest] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=MergeIndexesEmbeddedTest -Dtests.seed=AC20D7B067BC42E7 -Dtests.slow=true -Dtests.locale=it -Dtests.timezone=America/Knox_IN -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 0.00s J1 | MergeIndexesEmbeddedTest (suite) <<< [junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts): [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_AC20D7B067BC42E7-001\tempDir-005: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_AC20D7B067BC42E7-001\tempDir-005 [junit4] > at __randomizedtesting.SeedInfo.seed([AC20D7B067BC42E7]:0) [junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] Completed [11/135 (1!)] on J1 in 1.68s, 3 tests, 1 error <<< FAILURES! [...truncated 38847 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org