Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/318/ Java: 64bit/jdk-9.0.1 -XX:+UseCompressedOops -XX:+UseSerialGC
3 tests failed. FAILED: junit.framework.TestSuite.org.apache.lucene.index.TestBackwardsCompatibility Error Message: Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\backward-codecs\test\J0\temp\lucene.index.TestBackwardsCompatibility_CB97E5801B2B4C12-001\3.0.2-cfs-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\backward-codecs\test\J0\temp\lucene.index.TestBackwardsCompatibility_CB97E5801B2B4C12-001\3.0.2-cfs-001 Stack Trace: java.io.IOException: Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\backward-codecs\test\J0\temp\lucene.index.TestBackwardsCompatibility_CB97E5801B2B4C12-001\3.0.2-cfs-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\backward-codecs\test\J0\temp\lucene.index.TestBackwardsCompatibility_CB97E5801B2B4C12-001\3.0.2-cfs-001 at __randomizedtesting.SeedInfo.seed([CB97E5801B2B4C12]: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.base/java.lang.Thread.run(Thread.java:844) FAILED: junit.framework.TestSuite.org.apache.lucene.store.TestFilterDirectory Error Message: Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestFilterDirectory_A35D592D20AC5462-001\testThreadSafety-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestFilterDirectory_A35D592D20AC5462-001\testThreadSafety-001 Stack Trace: java.io.IOException: Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestFilterDirectory_A35D592D20AC5462-001\testThreadSafety-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestFilterDirectory_A35D592D20AC5462-001\testThreadSafety-001 at __randomizedtesting.SeedInfo.seed([A35D592D20AC5462]: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.base/java.lang.Thread.run(Thread.java:844) FAILED: org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingSorting Error Message: Should have exactly 4 documents returned expected:<4> but was:<3> Stack Trace: java.lang.AssertionError: Should have exactly 4 documents returned expected:<4> but was:<3> at __randomizedtesting.SeedInfo.seed([5B518ACB2DC761CE:456982C3516CDB4E]: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.apache.solr.cloud.DocValuesNotIndexedTest.checkSortOrder(DocValuesNotIndexedTest.java:260) at org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingSorting(DocValuesNotIndexedTest.java:245) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) 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:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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 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.base/java.lang.Thread.run(Thread.java:844) Build Log: [...truncated 395 lines...] [junit4] Suite: org.apache.lucene.store.TestFilterDirectory [junit4] IGNOR/A 0.00s J0 | TestFilterDirectory.testFsyncDoesntCreateNewFiles [junit4] > Assumption #1: test only works for FSDirectory subclasses [junit4] IGNOR/A 0.00s J0 | TestFilterDirectory.testPendingDeletions [junit4] > Assumption #1: we can only install VirusCheckingFS on an FSDirectory [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1465, maxMBSortInHeap=5.642542507285505, sim=RandomSimilarity(queryNorm=false): {}, locale=es-PH, timezone=America/Whitehorse [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 9.0.1 (64-bit)/cpus=3,threads=1,free=74754104,total=97386496 [junit4] 2> NOTE: All tests run in this JVM: [TestSPIClassIterator, TestDocument, TestLucene50StoredFieldsFormat, TestBooleanSimilarity, TestLogMergePolicy, TestLucene70SegmentInfoFormat, TestIntArrayDocIdSet, TestConsistentFieldNumbers, TestSortedSetSortField, TestFieldCacheRewriteMethod, TestSnapshotDeletionPolicy, TestPriorityQueue, TestBooleanQueryVisitSubscorers, TestNorms, TestDirectoryReader, TestIndexWriterWithThreads, TestAddIndexes, TestNumericDocValuesUpdates, TestIndexManyDocuments, TestRecyclingIntBlockAllocator, TestSpanCollection, TestIntroSorter, TestSimpleAttributeImpl, TestLongBitSet, TestFilterDirectory] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestFilterDirectory -Dtests.seed=A35D592D20AC5462 -Dtests.slow=true -Dtests.locale=es-PH -Dtests.timezone=America/Whitehorse -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 0.00s J0 | TestFilterDirectory (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-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestFilterDirectory_A35D592D20AC5462-001\testThreadSafety-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestFilterDirectory_A35D592D20AC5462-001\testThreadSafety-001 [junit4] > at __randomizedtesting.SeedInfo.seed([A35D592D20AC5462]:0) [junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329) [junit4] > at java.base/java.lang.Thread.run(Thread.java:844) [junit4] Completed [52/456 (1!)] on J0 in 0.53s, 44 tests, 1 error, 2 skipped <<< FAILURES! [...truncated 4215 lines...] [junit4] Suite: org.apache.lucene.index.TestBackwardsCompatibility [junit4] IGNOR/A 0.00s J0 | TestBackwardsCompatibility.testCreateSortedIndex [junit4] > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes [junit4] IGNOR/A 0.00s J0 | TestBackwardsCompatibility.testCreateSingleSegmentNoCFS [junit4] > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes [junit4] IGNOR/A 0.00s J0 | TestBackwardsCompatibility.testCreateSingleSegmentCFS [junit4] > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes [junit4] IGNOR/A 0.00s J0 | TestBackwardsCompatibility.testCreateCFS [junit4] > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes [junit4] IGNOR/A 0.00s J0 | TestBackwardsCompatibility.testCreateEmptyIndex [junit4] > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes [junit4] IGNOR/A 0.00s J0 | TestBackwardsCompatibility.testCreateMoreTermsIndex [junit4] > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes [junit4] IGNOR/A 0.00s J0 | TestBackwardsCompatibility.testCreateNoCFS [junit4] > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes [junit4] IGNOR/A 0.00s J0 | TestBackwardsCompatibility.testCreateIndexWithDocValuesUpdates [junit4] > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes [junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarity(queryNorm=true): {content6=IB LL-LZ(0.3), content5=DFR I(n)BZ(0.3), content2=DFR I(n)Z(0.3), utf8=DFR I(F)2, fie?ld=ClassicSimilarity, content=LM Jelinek-Mercer(0.100000), autf8=IB LL-L2}, locale=ar-JO, timezone=GMT0 [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 9.0.1 (64-bit)/cpus=3,threads=1,free=53382120,total=97386496 [junit4] 2> NOTE: All tests run in this JVM: [TestLucene54DocValuesFormat, TestManyPointsInOldIndex, TestBackwardsCompatibility] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestBackwardsCompatibility -Dtests.seed=CB97E5801B2B4C12 -Dtests.slow=true -Dtests.locale=ar-JO -Dtests.timezone=GMT0 -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 0.00s J0 | TestBackwardsCompatibility (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-7.x-Windows\lucene\build\backward-codecs\test\J0\temp\lucene.index.TestBackwardsCompatibility_CB97E5801B2B4C12-001\3.0.2-cfs-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\backward-codecs\test\J0\temp\lucene.index.TestBackwardsCompatibility_CB97E5801B2B4C12-001\3.0.2-cfs-001 [junit4] > at __randomizedtesting.SeedInfo.seed([CB97E5801B2B4C12]:0) [junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329) [junit4] > at java.base/java.lang.Thread.run(Thread.java:844) [junit4] Completed [8/8 (1!)] on J0 in 16.01s, 30 tests, 1 error, 8 skipped <<< FAILURES! [...truncated 8678 lines...] [junit4] Suite: org.apache.solr.cloud.DocValuesNotIndexedTest [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_5B518ACB2DC761CE-001\init-core-data-001 [junit4] 2> 2071319 WARN (SUITE-DocValuesNotIndexedTest-seed#[5B518ACB2DC761CE]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=98 numCloses=98 [junit4] 2> 2071320 INFO (SUITE-DocValuesNotIndexedTest-seed#[5B518ACB2DC761CE]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 2071321 INFO (SUITE-DocValuesNotIndexedTest-seed#[5B518ACB2DC761CE]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0) [junit4] 2> 2071321 INFO (SUITE-DocValuesNotIndexedTest-seed#[5B518ACB2DC761CE]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 2071322 INFO (SUITE-DocValuesNotIndexedTest-seed#[5B518ACB2DC761CE]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_5B518ACB2DC761CE-001\tempDir-001 [junit4] 2> 2071322 INFO (SUITE-DocValuesNotIndexedTest-seed#[5B518ACB2DC761CE]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 2071323 INFO (Thread-4756) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 2071323 INFO (Thread-4756) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 2071327 ERROR (Thread-4756) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 2071423 INFO (SUITE-DocValuesNotIndexedTest-seed#[5B518ACB2DC761CE]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:65133 [junit4] 2> 2071432 INFO (jetty-launcher-5208-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 2071432 INFO (jetty-launcher-5208-thread-2) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 2071432 INFO (jetty-launcher-5208-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@58ae6d7a{/solr,null,AVAILABLE} [junit4] 2> 2071433 INFO (jetty-launcher-5208-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4f0db840{/solr,null,AVAILABLE} [junit4] 2> 2071434 INFO (jetty-launcher-5208-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4041e36a{SSL,[ssl, http/1.1]}{127.0.0.1:65138} [junit4] 2> 2071434 INFO (jetty-launcher-5208-thread-2) [ ] o.e.j.s.Server Started @2081361ms [junit4] 2> 2071434 INFO (jetty-launcher-5208-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=65138} [junit4] 2> 2071434 INFO (jetty-launcher-5208-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@b970e46{SSL,[ssl, http/1.1]}{127.0.0.1:65137} [junit4] 2> 2071434 INFO (jetty-launcher-5208-thread-1) [ ] o.e.j.s.Server Started @2081361ms [junit4] 2> 2071434 INFO (jetty-launcher-5208-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=65137} [junit4] 2> 2071434 ERROR (jetty-launcher-5208-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2071434 ERROR (jetty-launcher-5208-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2071434 INFO (jetty-launcher-5208-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.2.0 [junit4] 2> 2071435 INFO (jetty-launcher-5208-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.2.0 [junit4] 2> 2071435 INFO (jetty-launcher-5208-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2071435 INFO (jetty-launcher-5208-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2071435 INFO (jetty-launcher-5208-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2071435 INFO (jetty-launcher-5208-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2071435 INFO (jetty-launcher-5208-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-11-20T23:50:28.390097300Z [junit4] 2> 2071435 INFO (jetty-launcher-5208-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-11-20T23:50:28.390097300Z [junit4] 2> 2071438 INFO (jetty-launcher-5208-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2071438 INFO (jetty-launcher-5208-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2071442 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15fdbd71ab90002, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 2071443 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15fdbd71ab90001, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 2071445 INFO (jetty-launcher-5208-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:65133/solr [junit4] 2> 2071445 INFO (jetty-launcher-5208-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:65133/solr [junit4] 2> 2071448 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15fdbd71ab90004, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 2071552 INFO (jetty-launcher-5208-thread-2) [n:127.0.0.1:65138_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2071552 INFO (jetty-launcher-5208-thread-1) [n:127.0.0.1:65137_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2071553 INFO (jetty-launcher-5208-thread-2) [n:127.0.0.1:65138_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:65138_solr [junit4] 2> 2071554 INFO (jetty-launcher-5208-thread-1) [n:127.0.0.1:65137_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65137_solr [junit4] 2> 2071554 INFO (jetty-launcher-5208-thread-2) [n:127.0.0.1:65138_solr ] o.a.s.c.Overseer Overseer (id=99039433738223621-127.0.0.1:65138_solr-n_0000000000) starting [junit4] 2> 2071555 INFO (zkCallback-5219-thread-1-processing-n:127.0.0.1:65138_solr) [n:127.0.0.1:65138_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2071556 INFO (zkCallback-5220-thread-1-processing-n:127.0.0.1:65137_solr) [n:127.0.0.1:65137_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2071563 INFO (jetty-launcher-5208-thread-2) [n:127.0.0.1:65138_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65138_solr [junit4] 2> 2071565 INFO (zkCallback-5220-thread-1-processing-n:127.0.0.1:65137_solr) [n:127.0.0.1:65137_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2071565 INFO (zkCallback-5219-thread-1-processing-n:127.0.0.1:65138_solr) [n:127.0.0.1:65138_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2071614 INFO (jetty-launcher-5208-thread-2) [n:127.0.0.1:65138_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65138.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d3470dd [junit4] 2> 2071629 INFO (jetty-launcher-5208-thread-2) [n:127.0.0.1:65138_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65138.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d3470dd [junit4] 2> 2071630 INFO (jetty-launcher-5208-thread-2) [n:127.0.0.1:65138_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65138.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d3470dd [junit4] 2> 2071630 INFO (jetty-launcher-5208-thread-2) [n:127.0.0.1:65138_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_5B518ACB2DC761CE-001\tempDir-001\node2\. [junit4] 2> 2071651 INFO (jetty-launcher-5208-thread-1) [n:127.0.0.1:65137_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65137.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d3470dd [junit4] 2> 2071657 INFO (jetty-launcher-5208-thread-1) [n:127.0.0.1:65137_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65137.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d3470dd [junit4] 2> 2071657 INFO (jetty-launcher-5208-thread-1) [n:127.0.0.1:65137_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65137.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d3470dd [junit4] 2> 2071658 INFO (jetty-launcher-5208-thread-1) [n:127.0.0.1:65137_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_5B518ACB2DC761CE-001\tempDir-001\node1\. [junit4] 2> 2071682 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15fdbd71ab90007, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 2071685 INFO (SUITE-DocValuesNotIndexedTest-seed#[5B518ACB2DC761CE]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2071687 INFO (SUITE-DocValuesNotIndexedTest-seed#[5B518ACB2DC761CE]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:65133/solr ready [junit4] 2> 2071707 INFO (qtp6451764-23406) [n:127.0.0.1:65138_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf1&maxShardsPerNode=2&name=dv_coll&nrtReplicas=1&action=CREATE&numShards=4&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2071709 INFO (OverseerThreadFactory-9835-thread-1-processing-n:127.0.0.1:65138_solr) [n:127.0.0.1:65138_solr ] o.a.s.c.CreateCollectionCmd Create collection dv_coll [junit4] 2> 2071819 INFO (OverseerStateUpdate-99039433738223621-127.0.0.1:65138_solr-n_0000000000) [n:127.0.0.1:65138_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"dv_coll", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"dv_coll_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:65138/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2071821 INFO (OverseerStateUpdate-99039433738223621-127.0.0.1:65138_solr-n_0000000000) [n:127.0.0.1:65138_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"dv_coll", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"dv_coll_shard2_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:65137/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2071824 INFO (OverseerStateUpdate-99039433738223621-127.0.0.1:65138_solr-n_0000000000) [n:127.0.0.1:65138_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"dv_coll", [junit4] 2> "shard":"shard3", [junit4] 2> "core":"dv_coll_shard3_replica_n4", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:65138/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2071828 INFO (OverseerStateUpdate-99039433738223621-127.0.0.1:65138_solr-n_0000000000) [n:127.0.0.1:65138_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"dv_coll", [junit4] 2> "shard":"shard4", [junit4] 2> "core":"dv_coll_shard4_replica_n6", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:65137/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2072036 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf1&newCollection=true&name=dv_coll_shard3_replica_n4&action=CREATE&numShards=4&collection=dv_coll&shard=shard3&wt=javabin&version=2&replicaType=NRT [junit4] 2> 2072037 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2072036 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf1&newCollection=true&name=dv_coll_shard1_replica_n1&action=CREATE&numShards=4&collection=dv_coll&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 2072047 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf1&newCollection=true&name=dv_coll_shard4_replica_n6&action=CREATE&numShards=4&collection=dv_coll&shard=shard4&wt=javabin&version=2&replicaType=NRT [junit4] 2> 2072047 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2072053 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf1&newCollection=true&name=dv_coll_shard2_replica_n2&action=CREATE&numShards=4&collection=dv_coll&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 2072158 INFO (zkCallback-5219-thread-1-processing-n:127.0.0.1:65138_solr) [n:127.0.0.1:65138_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2]) [junit4] 2> 2072158 INFO (zkCallback-5220-thread-1-processing-n:127.0.0.1:65137_solr) [n:127.0.0.1:65137_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2]) [junit4] 2> 2072159 INFO (zkCallback-5220-thread-2-processing-n:127.0.0.1:65137_solr) [n:127.0.0.1:65137_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2]) [junit4] 2> 2072159 INFO (zkCallback-5219-thread-2-processing-n:127.0.0.1:65138_solr) [n:127.0.0.1:65138_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2]) [junit4] 2> 2073059 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0 [junit4] 2> 2073060 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0 [junit4] 2> 2073060 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0 [junit4] 2> 2073077 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.IndexSchema [dv_coll_shard3_replica_n4] Schema name=minimal [junit4] 2> 2073077 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.s.IndexSchema [dv_coll_shard1_replica_n1] Schema name=minimal [junit4] 2> 2073079 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2073079 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'dv_coll_shard1_replica_n1' using configuration from collection dv_coll, trusted=true [junit4] 2> 2073080 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65138.solr.core.dv_coll.shard1.replica_n1' (registry 'solr.core.dv_coll.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d3470dd [junit4] 2> 2073080 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 2073080 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore [[dv_coll_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_5B518ACB2DC761CE-001\tempDir-001\node2\dv_coll_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_5B518ACB2DC761CE-001\tempDir-001\node2\.\dv_coll_shard1_replica_n1\data\] [junit4] 2> 2073081 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.s.IndexSchema [dv_coll_shard4_replica_n6] Schema name=minimal [junit4] 2> 2073082 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2073082 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.CoreContainer Creating SolrCore 'dv_coll_shard4_replica_n6' using configuration from collection dv_coll, trusted=true [junit4] 2> 2073082 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65137.solr.core.dv_coll.shard4.replica_n6' (registry 'solr.core.dv_coll.shard4.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d3470dd [junit4] 2> 2073082 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 2073082 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2073083 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.CoreContainer Creating SolrCore 'dv_coll_shard3_replica_n4' using configuration from collection dv_coll, trusted=true [junit4] 2> 2073082 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.SolrCore [[dv_coll_shard4_replica_n6] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_5B518ACB2DC761CE-001\tempDir-001\node1\dv_coll_shard4_replica_n6], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_5B518ACB2DC761CE-001\tempDir-001\node1\.\dv_coll_shard4_replica_n6\data\] [junit4] 2> 2073083 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65138.solr.core.dv_coll.shard3.replica_n4' (registry 'solr.core.dv_coll.shard3.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d3470dd [junit4] 2> 2073083 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0 [junit4] 2> 2073084 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 2073084 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.SolrCore [[dv_coll_shard3_replica_n4] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_5B518ACB2DC761CE-001\tempDir-001\node2\dv_coll_shard3_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_5B518ACB2DC761CE-001\tempDir-001\node2\.\dv_coll_shard3_replica_n4\data\] [junit4] 2> 2073089 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.s.IndexSchema [dv_coll_shard2_replica_n2] Schema name=minimal [junit4] 2> 2073090 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2073090 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'dv_coll_shard2_replica_n2' using configuration from collection dv_coll, trusted=true [junit4] 2> 2073091 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65137.solr.core.dv_coll.shard2.replica_n2' (registry 'solr.core.dv_coll.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d3470dd [junit4] 2> 2073091 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 2073091 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.SolrCore [[dv_coll_shard2_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_5B518ACB2DC761CE-001\tempDir-001\node1\dv_coll_shard2_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_5B518ACB2DC761CE-001\tempDir-001\node1\.\dv_coll_shard2_replica_n2\data\] [junit4] 2> 2073167 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2073167 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2073168 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2073168 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2073169 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2073169 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2073169 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2073169 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2073172 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2073172 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2073172 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.s.SolrIndexSearcher Opening [Searcher@64c74682[dv_coll_shard4_replica_n6] main] [junit4] 2> 2073174 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2073174 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2073174 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.SolrIndexSearcher Opening [Searcher@793248a6[dv_coll_shard3_replica_n4] main] [junit4] 2> 2073174 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema [junit4] 2> 2073174 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2073175 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2073175 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest [junit4] 2> 2073175 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2073176 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2073176 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2073176 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema [junit4] 2> 2073176 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1584630941751443456 [junit4] 2> 2073176 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest [junit4] 2> 2073177 INFO (searcherExecutor-9841-thread-1-processing-n:127.0.0.1:65137_solr x:dv_coll_shard4_replica_n6 s:shard4 c:dv_coll r:core_node8) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.SolrCore [dv_coll_shard4_replica_n6] Registered new searcher Searcher@64c74682[dv_coll_shard4_replica_n6] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2073177 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2073177 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1584630941752492032 [junit4] 2> 2073179 INFO (searcherExecutor-9842-thread-1-processing-n:127.0.0.1:65138_solr x:dv_coll_shard3_replica_n4 s:shard3 c:dv_coll r:core_node7) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.SolrCore [dv_coll_shard3_replica_n4] Registered new searcher Searcher@793248a6[dv_coll_shard3_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2073180 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3fa3c8d3[dv_coll_shard1_replica_n1] main] [junit4] 2> 2073181 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 2073181 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 2073181 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:65137/solr/dv_coll_shard4_replica_n6/ [junit4] 2> 2073181 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 2073181 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.SyncStrategy https://127.0.0.1:65137/solr/dv_coll_shard4_replica_n6/ has no replicas [junit4] 2> 2073181 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 2073181 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2073181 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 2073181 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 2073181 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:65138/solr/dv_coll_shard3_replica_n4/ [junit4] 2> 2073182 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 2073182 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2073182 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.SyncStrategy https://127.0.0.1:65138/solr/dv_coll_shard3_replica_n4/ has no replicas [junit4] 2> 2073182 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema [junit4] 2> 2073182 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 2073182 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest [junit4] 2> 2073183 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2073183 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1584630941758783488 [junit4] 2> 2073184 INFO (searcherExecutor-9840-thread-1-processing-n:127.0.0.1:65138_solr x:dv_coll_shard1_replica_n1 s:shard1 c:dv_coll r:core_node3) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore [dv_coll_shard1_replica_n1] Registered new searcher Searcher@3fa3c8d3[dv_coll_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2073184 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:65137/solr/dv_coll_shard4_replica_n6/ shard4 [junit4] 2> 2073186 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:65138/solr/dv_coll_shard3_replica_n4/ shard3 [junit4] 2> 2073188 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 2073188 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 2073188 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:65138/solr/dv_coll_shard1_replica_n1/ [junit4] 2> 2073188 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 2073188 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:65138/solr/dv_coll_shard1_replica_n1/ has no replicas [junit4] 2> 2073188 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 2073191 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:65138/solr/dv_coll_shard1_replica_n1/ shard1 [junit4] 2> 2073232 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2073232 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2073235 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2073235 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2073236 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@69d78b45[dv_coll_shard2_replica_n2] main] [junit4] 2> 2073236 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2073236 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2073236 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema [junit4] 2> 2073238 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest [junit4] 2> 2073238 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2073240 INFO (searcherExecutor-9843-thread-1-processing-n:127.0.0.1:65137_solr x:dv_coll_shard2_replica_n2 s:shard2 c:dv_coll r:core_node5) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.SolrCore [dv_coll_shard2_replica_n2] Registered new searcher Searcher@69d78b45[dv_coll_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2073240 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1584630941818552320 [junit4] 2> 2073244 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 2073244 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 2073244 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:65137/solr/dv_coll_shard2_replica_n2/ [junit4] 2> 2073244 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 2073244 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.SyncStrategy https://127.0.0.1:65137/solr/dv_coll_shard2_replica_n2/ has no replicas [junit4] 2> 2073244 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 2073247 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:65137/solr/dv_coll_shard2_replica_n2/ shard2 [junit4] 2> 2073349 INFO (zkCallback-5219-thread-2-processing-n:127.0.0.1:65138_solr) [n:127.0.0.1:65138_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2]) [junit4] 2> 2073349 INFO (zkCallback-5219-thread-3-processing-n:127.0.0.1:65138_solr) [n:127.0.0.1:65138_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2]) [junit4] 2> 2073351 INFO (zkCallback-5220-thread-1-processing-n:127.0.0.1:65137_solr) [n:127.0.0.1:65137_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2]) [junit4] 2> 2073351 INFO (zkCallback-5220-thread-2-processing-n:127.0.0.1:65137_solr) [n:127.0.0.1:65137_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2]) [junit4] 2> 2073386 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 2073389 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 2073389 INFO (qtp1527165430-23395) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf1&newCollection=true&name=dv_coll_shard4_replica_n6&action=CREATE&numShards=4&collection=dv_coll&shard=shard4&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1342 [junit4] 2> 2073391 INFO (qtp6451764-23409) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf1&newCollection=true&name=dv_coll_shard3_replica_n4&action=CREATE&numShards=4&collection=dv_coll&shard=shard3&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1355 [junit4] 2> 2073395 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 2073397 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf1&newCollection=true&name=dv_coll_shard1_replica_n1&action=CREATE&numShards=4&collection=dv_coll&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1360 [junit4] 2> 2073399 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 2073399 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf1&newCollection=true&name=dv_coll_shard2_replica_n2&action=CREATE&numShards=4&collection=dv_coll&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1349 [junit4] 2> 2073407 INFO (qtp6451764-23406) [n:127.0.0.1:65138_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 2073503 INFO (zkCallback-5219-thread-3-processing-n:127.0.0.1:65138_solr) [n:127.0.0.1:65138_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2]) [junit4] 2> 2073503 INFO (zkCallback-5219-thread-2-processing-n:127.0.0.1:65138_solr) [n:127.0.0.1:65138_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2]) [junit4] 2> 2073503 INFO (zkCallback-5220-thread-2-processing-n:127.0.0.1:65137_solr) [n:127.0.0.1:65137_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2]) [junit4] 2> 2073503 INFO (zkCallback-5220-thread-1-processing-n:127.0.0.1:65137_solr) [n:127.0.0.1:65137_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2]) [junit4] 2> 2073712 INFO (OverseerCollectionConfigSetProcessor-99039433738223621-127.0.0.1:65138_solr-n_0000000000) [n:127.0.0.1:65138_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 2074407 INFO (qtp6451764-23406) [n:127.0.0.1:65138_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf1&maxShardsPerNode=2&name=dv_coll&nrtReplicas=1&action=CREATE&numShards=4&wt=javabin&version=2} status=0 QTime=2699 [junit4] 2> 2074412 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.SchemaManager managed schema loaded . version : 0 [junit4] 2> 2074414 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.IndexSchema [dv_coll_shard3_replica_n4] Schema name=minimal [junit4] 2> 2074415 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2074423 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.ZkController Persisted config data to node /configs/conf1/managed-schema [junit4] 2> 2074423 INFO (zkCallback-5219-thread-3-processing-n:127.0.0.1:65138_solr) [n:127.0.0.1:65138_solr ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/conf1/managed-schema, has occurred - updating schema from ZooKeeper ... [junit4] 2> 2074423 INFO (zkCallback-5220-thread-1-processing-n:127.0.0.1:65137_solr) [n:127.0.0.1:65137_solr ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/conf1/managed-schema, has occurred - updating schema from ZooKeeper ... [junit4] 2> 2074423 INFO (zkCallback-5220-thread-2-processing-n:127.0.0.1:65137_solr) [n:127.0.0.1:65137_solr ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/conf1/managed-schema, has occurred - updating schema from ZooKeeper ... [junit4] 2> 2074423 INFO (zkCallback-5219-thread-2-processing-n:127.0.0.1:65138_solr) [n:127.0.0.1:65138_solr ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/conf1/managed-schema, has occurred - updating schema from ZooKeeper ... [junit4] 2> 2074425 INFO (zkCallback-5220-thread-1-processing-n:127.0.0.1:65137_solr) [n:127.0.0.1:65137_solr ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from ZooKeeper [junit4] 2> 2074425 INFO (zkCallback-5220-thread-2-processing-n:127.0.0.1:65137_solr) [n:127.0.0.1:65137_solr ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from ZooKeeper [junit4] 2> 2074427 INFO (zkCallback-5220-thread-1-processing-n:127.0.0.1:65137_solr) [n:127.0.0.1:65137_solr ] o.a.s.s.IndexSchema [dv_coll_shard2_replica_n2] Schema name=minimal [junit4] 2> 2074427 INFO (zkCallback-5220-thread-2-processing-n:127.0.0.1:65137_solr) [n:127.0.0.1:65137_solr ] o.a.s.s.IndexSchema [dv_coll_shard4_replica_n6] Schema name=minimal [junit4] 2> 2074429 INFO (zkCallback-5219-thread-2-processing-n:127.0.0.1:65138_solr) [n:127.0.0.1:65138_solr ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from ZooKeeper [junit4] 2> 2074430 INFO (zkCallback-5220-thread-1-processing-n:127.0.0.1:65137_solr) [n:127.0.0.1:65137_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2074430 INFO (zkCallback-5220-thread-1-processing-n:127.0.0.1:65137_solr) [n:127.0.0.1:65137_solr ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 5 ms [junit4] 2> 2074431 INFO (zkCallback-5220-thread-2-processing-n:127.0.0.1:65137_solr) [n:127.0.0.1:65137_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2074432 INFO (zkCallback-5220-thread-2-processing-n:127.0.0.1:65137_solr) [n:127.0.0.1:65137_solr ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 6 ms [junit4] 2> 2074432 INFO (zkCallback-5219-thread-2-processing-n:127.0.0.1:65138_solr) [n:127.0.0.1:65138_solr ] o.a.s.s.IndexSchema [dv_coll_shard1_replica_n1] Schema name=minimal [junit4] 2> 2074463 INFO (zkCallback-5219-thread-2-processing-n:127.0.0.1:65138_solr) [n:127.0.0.1:65138_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2074463 INFO (zkCallback-5219-thread-2-processing-n:127.0.0.1:65138_solr) [n:127.0.0.1:65138_solr ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 33 ms [junit4] 2> 2074463 INFO (Thread-4778) [n:127.0.0.1:65138_solr ] o.a.s.c.SolrCore config update listener called for core dv_coll_shard1_replica_n1 [junit4] 2> 2074466 INFO (Thread-4778) [n:127.0.0.1:65138_solr ] o.a.s.c.SolrCore config update listener called for core dv_coll_shard3_replica_n4 [junit4] 2> 2074467 INFO (Thread-4778) [n:127.0.0.1:65138_solr ] o.a.s.c.SolrCore core reload dv_coll_shard3_replica_n4 [junit4] 2> 2074469 INFO (Thread-4779) [n:127.0.0.1:65137_solr ] o.a.s.c.SolrCore config update listener called for core dv_coll_shard2_replica_n2 [junit4] 2> 2074470 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0 [junit4] 2> 2074470 INFO (Thread-4779) [n:127.0.0.1:65137_solr ] o.a.s.c.SolrCore config update listener called for core dv_coll_shard4_replica_n6 [junit4] 2> 2074477 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.IndexSchema [dv_coll_shard3_replica_n4] Schema name=minimal [junit4] 2> 2074481 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2074481 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.CoreContainer Reloading SolrCore 'dv_coll_shard3_replica_n4' using configuration from collection dv_coll [junit4] 2> 2074517 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65138.solr.core.dv_coll.shard3.replica_n4' (registry 'solr.core.dv_coll.shard3.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d3470dd [junit4] 2> 2074518 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.SolrCore [[dv_coll_shard3_replica_n4] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_5B518ACB2DC761CE-001\tempDir-001\node2\dv_coll_shard3_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_5B518ACB2DC761CE-001\tempDir-001\node2\.\dv_coll_shard3_replica_n4\data\] [junit4] 2> 2074602 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2074602 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2074603 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.SolrIndexSearcher Opening [Searcher@5e98791f[dv_coll_shard3_replica_n4] main] [junit4] 2> 2074604 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2074605 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2074605 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema [junit4] 2> 2074605 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.ZkIndexSchemaReader Current schema version 1 is already the latest [junit4] 2> 2074605 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2074606 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1584630943250907136 [junit4] 2> 2074609 INFO (searcherExecutor-9860-thread-1-processing-n:127.0.0.1:65138_solr x:dv_coll_shard3_replica_n4 s:shard3 c:dv_coll r:core_node7) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.SolrCore [dv_coll_shard3_replica_n4] Registered new searcher Searcher@5e98791f[dv_coll_shard3_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2074614 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 2074614 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.SolrIndexSearcher Opening [Searcher@12af7df0[dv_coll_shard3_replica_n4] main] [junit4] 2> 2074617 INFO (searcherExecutor-9860-thread-1-processing-n:127.0.0.1:65138_solr x:dv_coll_shard3_replica_n4 s:shard3 c:dv_coll r:core_node7) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.SolrCore [dv_coll_shard3_replica_n4] Registered new searcher Searcher@12af7df0[dv_coll_shard3_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2074617 INFO (zkCallback-5219-thread-3-processing-n:127.0.0.1:65138_solr) [n:127.0.0.1:65138_solr ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from ZooKeeper [junit4] 2> 2074620 INFO (zkCallback-5219-thread-3-processing-n:127.0.0.1:65138_solr) [n:127.0.0.1:65138_solr ] o.a.s.s.IndexSchema [dv_coll_shard3_replica_n4] Schema name=minimal [junit4] 2> 2074622 INFO (zkCallback-5219-thread-3-processing-n:127.0.0.1:65138_solr) [n:127.0.0.1:65138_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2074622 INFO (zkCallback-5219-thread-3-processing-n:127.0.0.1:65138_solr) [n:127.0.0.1:65138_solr ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 5 ms [junit4] 2> 2074623 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.ManagedIndexSchema Waiting up to 599 secs for 3 replicas to apply schema update version 1 for collection dv_coll [junit4] 2> 2074623 INFO (Thread-4778) [n:127.0.0.1:65138_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0 [junit4] 2> 2075158 INFO (Thread-4778) [n:127.0.0.1:65138_solr ] o.a.s.s.IndexSchema [dv_coll_shard3_replica_n4] Schema name=minimal [junit4] 2> 2075160 INFO (Thread-4778) [n:127.0.0.1:65138_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2075160 INFO (Thread-4778) [n:127.0.0.1:65138_solr ] o.a.s.c.CoreContainer Reloading SolrCore 'dv_coll_shard3_replica_n4' using configuration from collection dv_coll [junit4] 2> 2075164 INFO (qtp6451764-23407) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.S.Request [dv_coll_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2075168 INFO (qtp1527165430-23398) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.S.Request [dv_coll_shard4_replica_n6] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2075174 INFO (qtp1527165430-23400) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.S.Request [dv_coll_shard2_replica_n2] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2075175 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.ManagedIndexSchema Took 552.0ms for 3 replicas to apply schema update version 1 for collection dv_coll [junit4] 2> 2075175 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.S.Request [dv_coll_shard3_replica_n4] webapp=/solr path=/schema params={wt=javabin&version=2} status=0 QTime=764 [junit4] 2> 2075175 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.SolrCore [dv_coll_shard3_replica_n4] CLOSING SolrCore org.apache.solr.core.SolrCore@23f7e2ab [junit4] 2> 2075175 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.dv_coll.shard3.replica_n4, tag=603447979 [junit4] 2> 2075175 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1f1a9f44: rootName = solr_65138, domain = solr.core.dv_coll.shard3.replica_n4, service url = null, agent id = null] for registry solr.core.dv_coll.shard3.replica_n4 / com.codahale.metrics.MetricRegistry@2709c845 [junit4] 2> 2075181 INFO (qtp6451764-23404) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.dv_coll.shard3.leader, tag=603447979 [junit4] 2> 2075196 INFO (Thread-4778) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65138.solr.core.dv_coll.shard3.replica_n4' (registry 'solr.core.dv_coll.shard3.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d3470dd [junit4] 2> 2075196 INFO (Thread-4778) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.SolrCore [[dv_coll_shard3_replica_n4] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_5B518ACB2DC761CE-001\tempDir-001\node2\dv_coll_shard3_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_5B518ACB2DC761CE-001\tempDir-001\node2\.\dv_coll_shard3_replica_n4\data\] [junit4] 2> 2075215 INFO (TEST-DocValuesNotIndexedTest.testGroupingSorting-seed#[5B518ACB2DC761CE]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testGroupingSorting [junit4] 2> 2075228 INFO (qtp6451764-23417) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica_n4] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:65138/solr/dv_coll_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1584630943897878528)} 0 1 [junit4] 2> 2075232 INFO (qtp1527165430-23393) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard4_replica_n6] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:65138/solr/dv_coll_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1584630943906267136)} 0 1 [junit4] 2> 2075233 INFO (qtp1527165430-23396) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:65138/solr/dv_coll_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1584630943896829952)} 0 11 [junit4] 2> 2075233 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1584630943890538496)} 0 17 [junit4] 2> 2075256 INFO (qtp6451764-23411) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1584630943932481536,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 2075256 INFO (qtp6451764-23411) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 2075272 INFO (qtp1527165430-23397) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1584630943949258752,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 2075272 INFO (qtp1527165430-23397) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 2075273 INFO (qtp6451764-23416) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1584630943950307328,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 2075273 INFO (qtp6451764-23416) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 2075273 INFO (Thread-4778) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2075273 INFO (Thread-4778) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2075274 INFO (qtp6451764-23411) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@275313c[dv_coll_shard1_replica_n1] main] [junit4] 2> 2075274 INFO (qtp6451764-23416) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 2075275 INFO (qtp6451764-23416) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica_n4] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:65138/solr/dv_coll_shard3_replica_n4/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 2 [junit4] 2> 2075275 INFO (qtp6451764-23411) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 2075276 INFO (qtp1527165430-23397) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@4baf050b[dv_coll_shard2_replica_n2] main] [junit4] 2> 2075276 INFO (qtp1527165430-23397) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 2075276 INFO (searcherExecutor-9840-thread-1-processing-n:127.0.0.1:65138_solr x:dv_coll_shard1_replica_n1 s:shard1 c:dv_coll r:core_node3) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore [dv_coll_shard1_replica_n1] Registered new searcher Searcher@275313c[dv_coll_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2075277 INFO (searcherExecutor-9843-thread-1-processing-n:127.0.0.1:65137_solr x:dv_coll_shard2_replica_n2 s:shard2 c:dv_coll r:core_node5) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.SolrCore [dv_coll_shard2_replica_n2] Registered new searcher Searcher@4baf050b[dv_coll_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2075278 INFO (qtp6451764-23411) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:65138/solr/dv_coll_shard3_replica_n4/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 21 [junit4] 2> 2075278 INFO (qtp1527165430-23397) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:65138/solr/dv_coll_shard3_replica_n4/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 5 [junit4] 2> 2075278 INFO (Thread-4778) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.SolrIndexSearcher Opening [Searcher@2a8e98a0[dv_coll_shard3_replica_n4] main] [junit4] 2> 2075279 INFO (Thread-4778) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2075280 INFO (Thread-4778) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2075280 INFO (Thread-4778) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema [junit4] 2> 2075281 INFO (Thread-4778) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.ZkIndexSchemaReader Current schema version 1 is already the latest [junit4] 2> 2075281 INFO (Thread-4778) [n:127.0.0.1:65138_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2075283 INFO (qtp1527165430-23399) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1584630943960793088,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 2075283 INFO (qtp1527165430-23399) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 2075283 INFO (qtp1527165430-23399) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.s.SolrIndexSearcher Opening [Searcher@cac3126[dv_coll_shard4_replica_n6] main] [junit4] 2> 2075283 INFO (qtp1527165430-23399) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 2075283 INFO (searcherExecutor-9841-thread-1-processing-n:127.0.0.1:65137_solr x:dv_coll_shard4_replica_n6 s:shard4 c:dv_coll r:core_node8) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.SolrCore [dv_coll_shard4_replica_n6] Registered new searcher Searcher@cac3126[dv_coll_shard4_replica_n6] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2075283 INFO (qtp1527165430-23399) [n:127.0.0.1:65137_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard4_replica_n6] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:65138/solr/dv_coll_shard3_replica_n4/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 1 [junit4] 2> 2075283 INFO (qtp6451764-23402) [n:127.0.0.1:65138_solr c:dv_coll s: [...truncated too long message...] 2> 2075696 INFO (coreCloseExecutor-9870-thread-2) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.dv_coll.shard2.replica_n2, tag=357402558 [junit4] 2> 2075696 INFO (coreCloseExecutor-9870-thread-2) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@42a83c4b: rootName = solr_65137, domain = solr.core.dv_coll.shard2.replica_n2, service url = null, agent id = null] for registry solr.core.dv_coll.shard2.replica_n2 / com.codahale.metrics.MetricRegistry@2534d3ef [junit4] 2> 2075701 INFO (coreCloseExecutor-9869-thread-2) [n:127.0.0.1:65138_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.dv_coll.shard1.leader, tag=606055212 [junit4] 2> 2075702 INFO (coreCloseExecutor-9870-thread-2) [n:127.0.0.1:65137_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.dv_coll.shard2.leader, tag=357402558 [junit4] 2> 2075706 INFO (jetty-closer-5209-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@58ae6d7a{/solr,null,UNAVAILABLE} [junit4] 2> 2075706 INFO (jetty-closer-5209-thread-1) [ ] o.a.s.c.Overseer Overseer (id=99039433738223621-127.0.0.1:65138_solr-n_0000000000) closing [junit4] 2> 2075706 INFO (OverseerStateUpdate-99039433738223621-127.0.0.1:65138_solr-n_0000000000) [n:127.0.0.1:65138_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:65138_solr [junit4] 2> 2075707 WARN (OverseerAutoScalingTriggerThread-99039433738223621-127.0.0.1:65138_solr-n_0000000000) [n:127.0.0.1:65138_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 2075708 INFO (jetty-closer-5209-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4f0db840{/solr,null,UNAVAILABLE} [junit4] 2> 2075708 ERROR (SUITE-DocValuesNotIndexedTest-seed#[5B518ACB2DC761CE]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 2075709 INFO (SUITE-DocValuesNotIndexedTest-seed#[5B518ACB2DC761CE]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:65133 65133 [junit4] 2> 2075710 INFO (Thread-4756) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:65133 65133 [junit4] 2> 2075714 WARN (Thread-4756) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 6 /solr/configs/conf1/managed-schema [junit4] 2> 4 /solr/configs/conf1 [junit4] 2> 3 /solr/aliases.json [junit4] 2> 2 /solr/security.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 12 /solr/collections/dv_coll/state.json [junit4] 2> 3 /solr/clusterstate.json [junit4] 2> 3 /solr/clusterprops.json [junit4] 2> 2 /solr/configs/conf1/managed-schema [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 3 /solr/live_nodes [junit4] 2> 3 /solr/collections [junit4] 2> [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_5B518ACB2DC761CE-001 [junit4] 2> Nov 20, 2017 11:50:32 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 4 leaked thread(s). [junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarity(queryNorm=true): {}, locale=en-KI, timezone=AGT [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 9.0.1 (64-bit)/cpus=3,threads=1,free=233499896,total=518979584 [junit4] 2> NOTE: All tests run in this JVM: [MultiTermTest, RecoveryZkTest, TestCodecSupport, TestBlobHandler, ResponseLogComponentTest, TestNoOpRegenerator, TriLevelCompositeIdRoutingTest, TestManagedSchemaAPI, JvmMetricsTest, TestRawTransformer, BigEndianAscendingWordSerializerTest, RequestHandlersTest, DistributedQueryComponentOptimizationTest, TestCloudSchemaless, TestRequestStatusCollectionAPI, TestGraphMLResponseWriter, BasicAuthIntegrationTest, TestHdfsBackupRestoreCore, TestRecovery, ParsingFieldUpdateProcessorsTest, SubstringBytesRefFilterTest, TestPushWriter, IndexSchemaTest, DistributedVersionInfoTest, ChaosMonkeySafeLeaderWithPullReplicasTest, ShowFileRequestHandlerTest, ReturnFieldsTest, HdfsBasicDistributedZkTest, ConfigSetsAPITest, TestFieldCollectionResource, TestCollectionAPI, TestIntervalFaceting, URLClassifyProcessorTest, TestXIncludeConfig, TolerantUpdateProcessorTest, QueryResultKeyTest, TestAddFieldRealTimeGet, TestPayloadCheckQParserPlugin, SolrInfoBeanTest, TestSchemaSimilarityResource, BlobRepositoryCloudTest, ZkStateWriterTest, BlockJoinFacetRandomTest, DisMaxRequestHandlerTest, AssignBackwardCompatibilityTest, TestRTimerTree, TestCorePropertiesReload, TestDistributedSearch, SuggestComponentTest, TestSQLHandler, RegexBoostProcessorTest, AnalyticsQueryTest, BaseCdcrDistributedZkTest, TestHalfAndHalfDocValues, CleanupOldIndexTest, DeleteNodeTest, TestPullReplicaErrorHandling, ComputePlanActionTest, SortByFunctionTest, TestBlendedInfixSuggestions, TestHighlightDedupGrouping, DistributedFacetPivotWhiteBoxTest, TestSolrConfigHandlerCloud, NodeAddedTriggerTest, PrimUtilsTest, SearchRateTriggerTest, TestLazyCores, NotRequiredUniqueKeyTest, SolrCLIZkUtilsTest, HighlighterTest, StatsReloadRaceTest, TestSolrConfigHandler, TestReqParamsAPI, DistributedQueryElevationComponentTest, TestSizeLimitedDistributedMap, TestMaxTokenLenTokenizer, ClusterStateUpdateTest, TestCustomSort, SecurityConfHandlerTest, TestDownShardTolerantSearch, TestStressInPlaceUpdates, HdfsWriteToMultipleCollectionsTest, SolrCmdDistributorTest, DistanceFunctionTest, DirectUpdateHandlerOptimizeTest, ScriptEngineTest, TestSearcherReuse, TestPolicyCloud, TestSerializedLuceneMatchVersion, TestSweetSpotSimilarityFactory, TimeZoneUtilsTest, TestUtils, DateMathParserTest, CdcrReplicationHandlerTest, AtomicUpdateProcessorFactoryTest, TestEmbeddedSolrServerAdminHandler, TestOmitPositions, BufferStoreTest, TestOrdValues, ResponseHeaderTest, TestSubQueryTransformer, TestDocBasedVersionConstraints, TestRandomRequestDistribution, CollectionReloadTest, DistribCursorPagingTest, SignatureUpdateProcessorFactoryTest, JSONWriterTest, CdcrVersionReplicationTest, TestLFUCache, BinaryUpdateRequestHandlerTest, RequiredFieldsTest, FastVectorHighlighterTest, TestIBSimilarityFactory, TestImpersonationWithHadoopAuth, DistribJoinFromCollectionTest, TestConfigSetProperties, TestHdfsUpdateLog, CoreMergeIndexesAdminHandlerTest, CloudMLTQParserTest, TestReplicationHandler, SpellCheckComponentTest, TestFreeTextSuggestions, TestBinaryField, TestCoreAdminApis, CircularListTest, AutoAddReplicasPlanActionTest, TestSegmentSorting, DistributedExpandComponentTest, TestDynamicLoading, TestSchemaNameResource, TestIndexingPerformance, ClassificationUpdateProcessorTest, ZkFailoverTest, LargeFieldTest, BJQParserTest, UUIDFieldTest, TestInfoStreamLogging, TestNumericRangeQuery32, TestDistribIDF, ManagedSchemaRoundRobinCloudTest, LeaderFailureAfterFreshStartTest, FieldAnalysisRequestHandlerTest, TestSystemCollAutoCreate, DeleteInactiveReplicaTest, TestLMDirichletSimilarityFactory, CdcrBootstrapTest, TestMacroExpander, OverriddenZkACLAndCredentialsProvidersTest, CurrencyFieldTypeTest, DirectSolrConnectionTest, SolrGangliaReporterTest, AddReplicaTest, TestJoin, RollingRestartTest, MinimalSchemaTest, TestTestInjection, TestPseudoReturnFields, DeleteShardTest, TestCollectionAPIs, TestQueryWrapperFilter, ShardRoutingTest, V2StandaloneTest, DistributedTermsComponentTest, TestFieldTypeCollectionResource, TestExpandComponent, TestLegacyTerms, TestSolrCloudSnapshots, TestStressRecovery, SolrRequestParserTest, DistributedFacetPivotLargeTest, DocValuesMissingTest, TestManagedSynonymGraphFilterFactory, TestAtomicUpdateErrorCases, AsyncCallRequestStatusResponseTest, TestPrepRecovery, ReplicationFactorTest, SuggesterWFSTTest, PKIAuthenticationIntegrationTest, TestSolrCloudWithDelegationTokens, TestSubQueryTransformerDistrib, RAMDirectoryFactoryTest, TestExactStatsCache, TestRandomFaceting, TestRandomDVFaceting, ZkCLITest, TestFaceting, DistributedSpellCheckComponentTest, ZkControllerTest, TestRealTimeGet, TestStressReorder, TestRangeQuery, SolrCoreTest, TestGroupingSearch, QueryElevationComponentTest, BadIndexSchemaTest, ConvertedLegacyTest, TestSort, TestFiltering, DirectUpdateHandlerTest, TestBadConfig, SoftAutoCommitTest, SolrIndexSplitterTest, SimplePostToolTest, TestCoreDiscovery, TestExtendedDismaxParser, SuggesterFSTTest, SuggesterTSTTest, SpatialFilterTest, PolyFieldTest, WordBreakSolrSpellCheckerTest, TestUpdate, DefaultValueUpdateProcessorTest, DocValuesMultiTest, XsltUpdateRequestHandlerTest, TestSurroundQueryParser, TestWriterPerf, TestQueryTypes, FileBasedSpellCheckerTest, TestValueSourceCache, TermVectorComponentTest, TestSolrQueryParser, TestJmxIntegration, TestAnalyzedSuggestions, JsonLoaderTest, SearchHandlerTest, SampleTest, NumericFieldsTest, TestConfig, TestFuzzyAnalyzedSuggestions, TestPhraseSuggestions, TestDFRSimilarityFactory, TestLMJelinekMercerSimilarityFactory, ResourceLoaderTest, ChaosMonkeyNothingIsSafeTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, CollectionsAPISolrJTest, DocValuesNotIndexedTest] [junit4] Completed [519/749 (1!)] on J0 in 4.91s, 4 tests, 1 failure <<< FAILURES! [...truncated 40902 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org