Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7792/
Java: 64bit/jdk-12-ea+33 -XX:+UseCompressedOops -XX:+UseG1GC

3 tests failed.
FAILED:  org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingDVOnly

Error Message:
Should be exactly one group with 1 entry of 10 for null for field intGSF 
expected:<1> but was:<0>

Stack Trace:
java.lang.AssertionError: Should be exactly one group with 1 entry of 10 for 
null for field intGSF expected:<1> but was:<0>
        at 
__randomizedtesting.SeedInfo.seed([36D6AADA7DA4CE1C:AD6DC48230FCFC42]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at org.junit.Assert.failNotEquals(Assert.java:834)
        at org.junit.Assert.assertEquals(Assert.java:645)
        at 
org.apache.solr.cloud.DocValuesNotIndexedTest.doGroupingDvOnly(DocValuesNotIndexedTest.java:395)
        at 
org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingDVOnly(DocValuesNotIndexedTest.java:321)
        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:567)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        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:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        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:835)


FAILED:  org.apache.solr.cloud.MissingSegmentRecoveryTest.testLeaderRecovery

Error Message:
Expected a collection with one shard and two replicas Timeout waiting to see 
state for collection=MissingSegmentRecoveryTest 
:DocCollection(MissingSegmentRecoveryTest//collections/MissingSegmentRecoveryTest/state.json/7)={
   "pullReplicas":"0",   "replicationFactor":"2",   "shards":{"shard1":{       
"range":"80000000-7fffffff",       "state":"active",       "replicas":{         
"core_node3":{           "core":"MissingSegmentRecoveryTest_shard1_replica_n1", 
          "base_url":"http://127.0.0.1:61524/solr";,           
"node_name":"127.0.0.1:61524_solr",           "state":"down",           
"type":"NRT",           "force_set_state":"false"},         "core_node4":{      
     "core":"MissingSegmentRecoveryTest_shard1_replica_n2",           
"base_url":"http://127.0.0.1:61525/solr";,           
"node_name":"127.0.0.1:61525_solr",           "state":"active",           
"type":"NRT",           "force_set_state":"false",           
"leader":"true"}}}},   "router":{"name":"compositeId"},   
"maxShardsPerNode":"1",   "autoAddReplicas":"false",   "nrtReplicas":"2",   
"tlogReplicas":"0"} Live Nodes: [127.0.0.1:61524_solr, 127.0.0.1:61525_solr] 
Last available state: 
DocCollection(MissingSegmentRecoveryTest//collections/MissingSegmentRecoveryTest/state.json/7)={
   "pullReplicas":"0",   "replicationFactor":"2",   "shards":{"shard1":{       
"range":"80000000-7fffffff",       "state":"active",       "replicas":{         
"core_node3":{           "core":"MissingSegmentRecoveryTest_shard1_replica_n1", 
          "base_url":"http://127.0.0.1:61524/solr";,           
"node_name":"127.0.0.1:61524_solr",           "state":"down",           
"type":"NRT",           "force_set_state":"false"},         "core_node4":{      
     "core":"MissingSegmentRecoveryTest_shard1_replica_n2",           
"base_url":"http://127.0.0.1:61525/solr";,           
"node_name":"127.0.0.1:61525_solr",           "state":"active",           
"type":"NRT",           "force_set_state":"false",           
"leader":"true"}}}},   "router":{"name":"compositeId"},   
"maxShardsPerNode":"1",   "autoAddReplicas":"false",   "nrtReplicas":"2",   
"tlogReplicas":"0"}

Stack Trace:
java.lang.AssertionError: Expected a collection with one shard and two replicas
Timeout waiting to see state for collection=MissingSegmentRecoveryTest 
:DocCollection(MissingSegmentRecoveryTest//collections/MissingSegmentRecoveryTest/state.json/7)={
  "pullReplicas":"0",
  "replicationFactor":"2",
  "shards":{"shard1":{
      "range":"80000000-7fffffff",
      "state":"active",
      "replicas":{
        "core_node3":{
          "core":"MissingSegmentRecoveryTest_shard1_replica_n1",
          "base_url":"http://127.0.0.1:61524/solr";,
          "node_name":"127.0.0.1:61524_solr",
          "state":"down",
          "type":"NRT",
          "force_set_state":"false"},
        "core_node4":{
          "core":"MissingSegmentRecoveryTest_shard1_replica_n2",
          "base_url":"http://127.0.0.1:61525/solr";,
          "node_name":"127.0.0.1:61525_solr",
          "state":"active",
          "type":"NRT",
          "force_set_state":"false",
          "leader":"true"}}}},
  "router":{"name":"compositeId"},
  "maxShardsPerNode":"1",
  "autoAddReplicas":"false",
  "nrtReplicas":"2",
  "tlogReplicas":"0"}
Live Nodes: [127.0.0.1:61524_solr, 127.0.0.1:61525_solr]
Last available state: 
DocCollection(MissingSegmentRecoveryTest//collections/MissingSegmentRecoveryTest/state.json/7)={
  "pullReplicas":"0",
  "replicationFactor":"2",
  "shards":{"shard1":{
      "range":"80000000-7fffffff",
      "state":"active",
      "replicas":{
        "core_node3":{
          "core":"MissingSegmentRecoveryTest_shard1_replica_n1",
          "base_url":"http://127.0.0.1:61524/solr";,
          "node_name":"127.0.0.1:61524_solr",
          "state":"down",
          "type":"NRT",
          "force_set_state":"false"},
        "core_node4":{
          "core":"MissingSegmentRecoveryTest_shard1_replica_n2",
          "base_url":"http://127.0.0.1:61525/solr";,
          "node_name":"127.0.0.1:61525_solr",
          "state":"active",
          "type":"NRT",
          "force_set_state":"false",
          "leader":"true"}}}},
  "router":{"name":"compositeId"},
  "maxShardsPerNode":"1",
  "autoAddReplicas":"false",
  "nrtReplicas":"2",
  "tlogReplicas":"0"}
        at 
__randomizedtesting.SeedInfo.seed([36D6AADA7DA4CE1C:668332D924857801]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at 
org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:310)
        at 
org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:288)
        at 
org.apache.solr.cloud.MissingSegmentRecoveryTest.testLeaderRecovery(MissingSegmentRecoveryTest.java:106)
        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:567)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        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:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        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:835)


FAILED:  
junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler

Error Message:
Could not remove the following files (in the order of attempts):    
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_36D6AADA7DA4CE1C-001\solr-instance-042\collection1\data\index.20190317090146277:
 java.nio.file.NoSuchFileException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_36D6AADA7DA4CE1C-001\solr-instance-042\collection1\data\index.20190317090146277
 

Stack Trace:
java.io.IOException: Could not remove the following files (in the order of 
attempts):
   
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_36D6AADA7DA4CE1C-001\solr-instance-042\collection1\data\index.20190317090146277:
 java.nio.file.NoSuchFileException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_36D6AADA7DA4CE1C-001\solr-instance-042\collection1\data\index.20190317090146277

        at __randomizedtesting.SeedInfo.seed([36D6AADA7DA4CE1C]:0)
        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:318)
        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:835)




Build Log:
[...truncated 13738 lines...]
   [junit4] Suite: org.apache.solr.cloud.DocValuesNotIndexedTest
   [junit4]   2> Creating dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_36D6AADA7DA4CE1C-001\init-core-data-001
   [junit4]   2> 1140782 WARN  
(SUITE-DocValuesNotIndexedTest-seed#[36D6AADA7DA4CE1C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=5 numCloses=5
   [junit4]   2> 1140782 INFO  
(SUITE-DocValuesNotIndexedTest-seed#[36D6AADA7DA4CE1C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 1140783 INFO  
(SUITE-DocValuesNotIndexedTest-seed#[36D6AADA7DA4CE1C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) 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> 1140783 INFO  
(SUITE-DocValuesNotIndexedTest-seed#[36D6AADA7DA4CE1C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 1140786 INFO  
(TEST-DocValuesNotIndexedTest.testGroupingSorting-seed#[36D6AADA7DA4CE1C]) [    
] o.a.s.SolrTestCaseJ4 ###Starting testGroupingSorting
   [junit4]   2> 1140786 INFO  
(TEST-DocValuesNotIndexedTest.testGroupingSorting-seed#[36D6AADA7DA4CE1C]) [    
] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_36D6AADA7DA4CE1C-001\tempDir-001
   [junit4]   2> 1140786 INFO  
(TEST-DocValuesNotIndexedTest.testGroupingSorting-seed#[36D6AADA7DA4CE1C]) [    
] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1140786 INFO  (ZkTestServer Run Thread) [    ] 
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1140786 INFO  (ZkTestServer Run Thread) [    ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1140887 INFO  
(TEST-DocValuesNotIndexedTest.testGroupingSorting-seed#[36D6AADA7DA4CE1C]) [    
] o.a.s.c.ZkTestServer start zk server on port:60469
   [junit4]   2> 1140887 INFO  
(TEST-DocValuesNotIndexedTest.testGroupingSorting-seed#[36D6AADA7DA4CE1C]) [    
] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:60469
   [junit4]   2> 1140887 INFO  
(TEST-DocValuesNotIndexedTest.testGroupingSorting-seed#[36D6AADA7DA4CE1C]) [    
] o.a.s.c.ZkTestServer connecting to 127.0.0.1 60469
   [junit4]   2> 1140892 INFO  (zkConnectionManagerCallback-7979-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1140896 INFO  (zkConnectionManagerCallback-7981-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1140898 INFO  (zkConnectionManagerCallback-7983-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1140901 WARN  (jetty-launcher-7984-thread-1) [    ] 
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 1140901 WARN  (jetty-launcher-7984-thread-2) [    ] 
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 1140901 INFO  (jetty-launcher-7984-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 1140901 INFO  (jetty-launcher-7984-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 1140901 INFO  (jetty-launcher-7984-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 1140901 INFO  (jetty-launcher-7984-thread-1) [    ] 
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: 
c4550056e785fb5665914545889f21dc136ad9e6; jvm 12+33
   [junit4]   2> 1140901 INFO  (jetty-launcher-7984-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 1140901 INFO  (jetty-launcher-7984-thread-2) [    ] 
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: 
c4550056e785fb5665914545889f21dc136ad9e6; jvm 12+33
   [junit4]   2> 1140902 INFO  (jetty-launcher-7984-thread-1) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1140902 INFO  (jetty-launcher-7984-thread-1) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1140902 INFO  (jetty-launcher-7984-thread-1) [    ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 1140902 INFO  (jetty-launcher-7984-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@6af4f550{/solr,null,AVAILABLE}
   [junit4]   2> 1140902 INFO  (jetty-launcher-7984-thread-2) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1140902 INFO  (jetty-launcher-7984-thread-2) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1140902 INFO  (jetty-launcher-7984-thread-2) [    ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 1140902 INFO  (jetty-launcher-7984-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@41dd8c1d{/solr,null,AVAILABLE}
   [junit4]   2> 1140903 INFO  (jetty-launcher-7984-thread-1) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@2177d70c{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:60480}
   [junit4]   2> 1140903 INFO  (jetty-launcher-7984-thread-1) [    ] 
o.e.j.s.Server Started @1140947ms
   [junit4]   2> 1140903 INFO  (jetty-launcher-7984-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=60480}
   [junit4]   2> 1140903 INFO  (jetty-launcher-7984-thread-2) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@8cbb617{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:60481}
   [junit4]   2> 1140903 INFO  (jetty-launcher-7984-thread-2) [    ] 
o.e.j.s.Server Started @1140948ms
   [junit4]   2> 1140903 ERROR (jetty-launcher-7984-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1140903 INFO  (jetty-launcher-7984-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1140903 INFO  (jetty-launcher-7984-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=60481}
   [junit4]   2> 1140903 INFO  (jetty-launcher-7984-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
9.0.0
   [junit4]   2> 1140903 INFO  (jetty-launcher-7984-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1140903 INFO  (jetty-launcher-7984-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1140903 INFO  (jetty-launcher-7984-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2019-03-17T08:57:25.444060200Z
   [junit4]   2> 1140904 ERROR (jetty-launcher-7984-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1140904 INFO  (jetty-launcher-7984-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1140904 INFO  (jetty-launcher-7984-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
9.0.0
   [junit4]   2> 1140904 INFO  (jetty-launcher-7984-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1140904 INFO  (jetty-launcher-7984-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1140904 INFO  (jetty-launcher-7984-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2019-03-17T08:57:25.445218200Z
   [junit4]   2> 1140907 INFO  (zkConnectionManagerCallback-7986-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1140907 INFO  (zkConnectionManagerCallback-7988-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1140908 INFO  (jetty-launcher-7984-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1140908 INFO  (jetty-launcher-7984-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1140966 INFO  (jetty-launcher-7984-thread-1) [    ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1140969 WARN  (jetty-launcher-7984-thread-1) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@2562bdd4[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1140972 WARN  (jetty-launcher-7984-thread-1) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@739c9f81[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1140974 INFO  (jetty-launcher-7984-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60469/solr
   [junit4]   2> 1140976 INFO  (zkConnectionManagerCallback-7996-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1140980 INFO  (zkConnectionManagerCallback-7998-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1141057 INFO  (jetty-launcher-7984-thread-1) 
[n:127.0.0.1:60480_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:60480_solr
   [junit4]   2> 1141057 INFO  (jetty-launcher-7984-thread-1) 
[n:127.0.0.1:60480_solr    ] o.a.s.c.Overseer Overseer 
(id=72078259936165894-127.0.0.1:60480_solr-n_0000000000) starting
   [junit4]   2> 1141060 INFO  (jetty-launcher-7984-thread-2) [    ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1141061 WARN  (jetty-launcher-7984-thread-2) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@4838cdc9[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1141063 INFO  (zkConnectionManagerCallback-8006-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1141065 WARN  (jetty-launcher-7984-thread-2) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@b849c46[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1141066 INFO  (jetty-launcher-7984-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60469/solr
   [junit4]   2> 1141067 INFO  (jetty-launcher-7984-thread-1) 
[n:127.0.0.1:60480_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:60469/solr ready
   [junit4]   2> 1141067 INFO  (jetty-launcher-7984-thread-1) 
[n:127.0.0.1:60480_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:60480_solr
   [junit4]   2> 1141071 INFO  
(OverseerStateUpdate-72078259936165894-127.0.0.1:60480_solr-n_0000000000) 
[n:127.0.0.1:60480_solr    ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:60480_solr
   [junit4]   2> 1141072 INFO  
(OverseerStateUpdate-72078259936165894-127.0.0.1:60480_solr-n_0000000000) 
[n:127.0.0.1:60480_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1141073 INFO  (zkCallback-8005-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1141073 INFO  (zkConnectionManagerCallback-8011-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1141075 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x10012cba7860008, likely client has closed socket
   [junit4]   2> 1141096 INFO  (zkConnectionManagerCallback-8013-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1141102 INFO  (jetty-launcher-7984-thread-2) 
[n:127.0.0.1:60481_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1141105 INFO  (jetty-launcher-7984-thread-2) 
[n:127.0.0.1:60481_solr    ] o.a.s.c.ZkController Publish 
node=127.0.0.1:60481_solr as DOWN
   [junit4]   2> 1141106 INFO  (jetty-launcher-7984-thread-2) 
[n:127.0.0.1:60481_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 1141106 INFO  (jetty-launcher-7984-thread-2) 
[n:127.0.0.1:60481_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:60481_solr
   [junit4]   2> 1141107 INFO  (zkCallback-8005-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1141107 INFO  (zkCallback-7997-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1141107 INFO  (jetty-launcher-7984-thread-1) 
[n:127.0.0.1:60480_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 1141131 INFO  (jetty-launcher-7984-thread-1) 
[n:127.0.0.1:60480_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_60480.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7d4347a9
   [junit4]   2> 1141138 INFO  (jetty-launcher-7984-thread-1) 
[n:127.0.0.1:60480_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_60480.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7d4347a9
   [junit4]   2> 1141138 INFO  (jetty-launcher-7984-thread-1) 
[n:127.0.0.1:60480_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_60480.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7d4347a9
   [junit4]   2> 1141141 INFO  (jetty-launcher-7984-thread-1) 
[n:127.0.0.1:60480_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_36D6AADA7DA4CE1C-001\tempDir-001\node1\.
   [junit4]   2> 1141141 INFO  (zkCallback-8012-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1141151 INFO  (zkConnectionManagerCallback-8021-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1141153 INFO  (jetty-launcher-7984-thread-2) 
[n:127.0.0.1:60481_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 1141155 INFO  (jetty-launcher-7984-thread-2) 
[n:127.0.0.1:60481_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:60469/solr ready
   [junit4]   2> 1141155 INFO  (jetty-launcher-7984-thread-2) 
[n:127.0.0.1:60481_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 1141173 INFO  (jetty-launcher-7984-thread-2) 
[n:127.0.0.1:60481_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_60481.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7d4347a9
   [junit4]   2> 1141182 INFO  (jetty-launcher-7984-thread-2) 
[n:127.0.0.1:60481_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_60481.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7d4347a9
   [junit4]   2> 1141182 INFO  (jetty-launcher-7984-thread-2) 
[n:127.0.0.1:60481_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_60481.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7d4347a9
   [junit4]   2> 1141183 INFO  (jetty-launcher-7984-thread-2) 
[n:127.0.0.1:60481_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_36D6AADA7DA4CE1C-001\tempDir-001\node2\.
   [junit4]   2> 1141221 INFO  (zkConnectionManagerCallback-8027-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1141225 INFO  
(TEST-DocValuesNotIndexedTest.testGroupingSorting-seed#[36D6AADA7DA4CE1C]) [    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1141228 INFO  
(TEST-DocValuesNotIndexedTest.testGroupingSorting-seed#[36D6AADA7DA4CE1C]) [    
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60469/solr ready
   [junit4]   2> 1141236 INFO  (qtp234947723-20627) [n:127.0.0.1:60480_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
collection.configName=conf1&maxShardsPerNode=2&name=dv_coll&nrtReplicas=1&action=CREATE&numShards=4&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1141247 INFO  
(OverseerThreadFactory-6308-thread-1-processing-n:127.0.0.1:60480_solr) 
[n:127.0.0.1:60480_solr    ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
dv_coll
   [junit4]   2> 1141356 INFO  
(OverseerStateUpdate-72078259936165894-127.0.0.1:60480_solr-n_0000000000) 
[n:127.0.0.1:60480_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":"http://127.0.0.1:60481/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1141357 INFO  
(OverseerStateUpdate-72078259936165894-127.0.0.1:60480_solr-n_0000000000) 
[n:127.0.0.1:60480_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":"http://127.0.0.1:60480/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1141359 INFO  
(OverseerStateUpdate-72078259936165894-127.0.0.1:60480_solr-n_0000000000) 
[n:127.0.0.1:60480_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":"http://127.0.0.1:60481/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1141359 INFO  
(OverseerStateUpdate-72078259936165894-127.0.0.1:60480_solr-n_0000000000) 
[n:127.0.0.1:60480_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":"http://127.0.0.1:60480/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1141564 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_solr    
x:dv_coll_shard2_replica_n2] 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> 1141564 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_solr    
x:dv_coll_shard3_replica_n4] 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> 1141564 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_solr    
x:dv_coll_shard2_replica_n2] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 1141567 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_solr    
x:dv_coll_shard1_replica_n1] 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> 1141567 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_solr    
x:dv_coll_shard4_replica_n6] 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> 1142576 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 1142576 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 1142598 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_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> 1142598 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_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> 1142601 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 1142601 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_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> 1142601 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_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> 1142601 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_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_60480.solr.core.dv_coll.shard2.replica_n2' (registry 
'solr.core.dv_coll.shard2.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7d4347a9
   [junit4]   2> 1142601 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_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> 1142601 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_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> 1142601 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_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-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_36D6AADA7DA4CE1C-001\tempDir-001\node1\dv_coll_shard2_replica_n2],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_36D6AADA7DA4CE1C-001\tempDir-001\node1\.\dv_coll_shard2_replica_n2\data\]
   [junit4]   2> 1142602 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_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_60481.solr.core.dv_coll.shard3.replica_n4' (registry 
'solr.core.dv_coll.shard3.replica_n4') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7d4347a9
   [junit4]   2> 1142602 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_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-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_36D6AADA7DA4CE1C-001\tempDir-001\node2\dv_coll_shard3_replica_n4],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_36D6AADA7DA4CE1C-001\tempDir-001\node2\.\dv_coll_shard3_replica_n4\data\]
   [junit4]   2> 1142606 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_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> 1142607 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 1142607 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_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> 1142607 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_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> 1142607 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_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_60481.solr.core.dv_coll.shard1.replica_n1' (registry 
'solr.core.dv_coll.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7d4347a9
   [junit4]   2> 1142607 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_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-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_36D6AADA7DA4CE1C-001\tempDir-001\node2\dv_coll_shard1_replica_n1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_36D6AADA7DA4CE1C-001\tempDir-001\node2\.\dv_coll_shard1_replica_n1\data\]
   [junit4]   2> 1142612 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_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> 1142614 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_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> 1142614 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_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> 1142615 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_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_60480.solr.core.dv_coll.shard4.replica_n6' (registry 
'solr.core.dv_coll.shard4.replica_n6') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7d4347a9
   [junit4]   2> 1142615 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_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-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_36D6AADA7DA4CE1C-001\tempDir-001\node1\dv_coll_shard4_replica_n6],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_36D6AADA7DA4CE1C-001\tempDir-001\node1\.\dv_coll_shard4_replica_n6\data\]
   [junit4]   2> 1142671 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_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> 1142671 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_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> 1142675 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_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> 1142675 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_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> 1142681 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_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> 1142681 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_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> 1142685 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_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> 1142685 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_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> 1142685 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7668b9f8[dv_coll_shard3_replica_n4] 
main]
   [junit4]   2> 1142687 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_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> 1142687 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_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> 1142687 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_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> 1142687 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_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> 1142687 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_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> 1142687 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_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 
1628242493810147328
   [junit4]   2> 1142687 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_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> 1142687 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_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> 1142687 INFO  
(searcherExecutor-6318-thread-1-processing-n:127.0.0.1:60481_solr 
x:dv_coll_shard3_replica_n4 c:dv_coll s:shard3 r:core_node7) 
[n:127.0.0.1:60481_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@7668b9f8[dv_coll_shard3_replica_n4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1142736 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_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> 1142736 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_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> 1142739 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/dv_coll/terms/shard3 to Terms{values={core_node7=0}, version=0}
   [junit4]   2> 1142739 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/dv_coll/leaders/shard3
   [junit4]   2> 1142740 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_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> 1142740 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_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> 1142740 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:60481/solr/dv_coll_shard3_replica_n4/
   [junit4]   2> 1142740 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_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> 1142740 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.c.SyncStrategy http://127.0.0.1:60481/solr/dv_coll_shard3_replica_n4/ has 
no replicas
   [junit4]   2> 1142740 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/dv_coll/leaders/shard3/leader after winning as 
/collections/dv_coll/leader_elect/shard3/election/72078259936165897-core_node7-n_0000000000
   [junit4]   2> 1142744 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_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: 
http://127.0.0.1:60481/solr/dv_coll_shard3_replica_n4/ shard3
   [junit4]   2> 1142746 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@6053621e[dv_coll_shard1_replica_n1] 
main]
   [junit4]   2> 1142747 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_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> 1142747 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_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> 1142747 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_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> 1142747 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_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> 1142748 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_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> 1142748 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_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 
1628242493874110464
   [junit4]   2> 1142750 INFO  
(searcherExecutor-6319-thread-1-processing-n:127.0.0.1:60481_solr 
x:dv_coll_shard1_replica_n1 c:dv_coll s:shard1 r:core_node3) 
[n:127.0.0.1:60481_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@6053621e[dv_coll_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1142752 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/dv_coll/terms/shard1 to Terms{values={core_node3=0}, version=0}
   [junit4]   2> 1142753 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/dv_coll/leaders/shard1
   [junit4]   2> 1142756 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_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> 1142756 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_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> 1142756 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:60481/solr/dv_coll_shard1_replica_n1/
   [junit4]   2> 1142756 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_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> 1142756 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.c.SyncStrategy http://127.0.0.1:60481/solr/dv_coll_shard1_replica_n1/ has 
no replicas
   [junit4]   2> 1142756 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/dv_coll/leaders/shard1/leader after winning as 
/collections/dv_coll/leader_elect/shard1/election/72078259936165897-core_node3-n_0000000000
   [junit4]   2> 1142757 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_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: 
http://127.0.0.1:60481/solr/dv_coll_shard1_replica_n1/ shard1
   [junit4]   2> 1142758 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@39f2d05d[dv_coll_shard2_replica_n2] 
main]
   [junit4]   2> 1142758 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_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> 1142760 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_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> 1142760 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_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> 1142761 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_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> 1142761 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_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> 1142761 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_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 
1628242493887741952
   [junit4]   2> 1142762 INFO  
(searcherExecutor-6317-thread-1-processing-n:127.0.0.1:60480_solr 
x:dv_coll_shard2_replica_n2 c:dv_coll s:shard2 r:core_node5) 
[n:127.0.0.1:60480_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@39f2d05d[dv_coll_shard2_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1142768 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/dv_coll/terms/shard2 to Terms{values={core_node5=0}, version=0}
   [junit4]   2> 1142768 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/dv_coll/leaders/shard2
   [junit4]   2> 1142770 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_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> 1142770 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_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> 1142770 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:60480/solr/dv_coll_shard2_replica_n2/
   [junit4]   2> 1142770 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_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> 1142771 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.c.SyncStrategy http://127.0.0.1:60480/solr/dv_coll_shard2_replica_n2/ has 
no replicas
   [junit4]   2> 1142771 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/dv_coll/leaders/shard2/leader after winning as 
/collections/dv_coll/leader_elect/shard2/election/72078259936165894-core_node5-n_0000000000
   [junit4]   2> 1142772 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_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: 
http://127.0.0.1:60480/solr/dv_coll_shard2_replica_n2/ shard2
   [junit4]   2> 1142779 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_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> 1142779 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_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> 1142783 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_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> 1142783 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_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> 1142784 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@29ea9bbb[dv_coll_shard4_replica_n6] 
main]
   [junit4]   2> 1142784 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_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> 1142785 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_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> 1142785 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_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> 1142785 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_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> 1142785 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_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> 1142786 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_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 
1628242493913956352
   [junit4]   2> 1142789 INFO  
(searcherExecutor-6320-thread-1-processing-n:127.0.0.1:60480_solr 
x:dv_coll_shard4_replica_n6 c:dv_coll s:shard4 r:core_node8) 
[n:127.0.0.1:60480_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@29ea9bbb[dv_coll_shard4_replica_n6] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1142790 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/dv_coll/terms/shard4 to Terms{values={core_node8=0}, version=0}
   [junit4]   2> 1142791 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/dv_coll/leaders/shard4
   [junit4]   2> 1142794 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_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> 1142794 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_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> 1142794 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:60480/solr/dv_coll_shard4_replica_n6/
   [junit4]   2> 1142794 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_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> 1142794 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.c.SyncStrategy http://127.0.0.1:60480/solr/dv_coll_shard4_replica_n6/ has 
no replicas
   [junit4]   2> 1142794 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/dv_coll/leaders/shard4/leader after winning as 
/collections/dv_coll/leader_elect/shard4/election/72078259936165894-core_node8-n_0000000000
   [junit4]   2> 1142795 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_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: 
http://127.0.0.1:60480/solr/dv_coll_shard4_replica_n6/ shard4
   [junit4]   2> 1142901 INFO  (zkCallback-7997-thread-2) [    ] 
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> 1142901 INFO  (zkCallback-7997-thread-1) [    ] 
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> 1142901 INFO  (zkCallback-8012-thread-2) [    ] 
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> 1142902 INFO  (zkCallback-8012-thread-1) [    ] 
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> 1142905 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_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> 1142906 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_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> 1142906 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_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> 1142906 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_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> 1142910 INFO  (qtp234947723-20634) [n:127.0.0.1:60480_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=1347
   [junit4]   2> 1142915 INFO  (qtp234947723-20636) [n:127.0.0.1:60480_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=1347
   [junit4]   2> 1142916 INFO  (qtp1558870416-20633) [n:127.0.0.1:60481_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=1352
   [junit4]   2> 1142916 INFO  (qtp1558870416-20640) [n:127.0.0.1:60481_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=1348
   [junit4]   2> 1142920 INFO  (qtp234947723-20627) [n:127.0.0.1:60480_solr    
] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
45 seconds. Check all shard replicas
   [junit4]   2> 1143022 INFO  (zkCallback-7997-thread-3) [    ] 
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> 1143022 INFO  (zkCallback-7997-thread-1) [    ] 
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> 1143022 INFO  (zkCallback-8012-thread-1) [    ] 
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> 1143022 INFO  (zkCallback-7997-thread-2) [    ] 
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> 1143022 INFO  (zkCallback-8012-thread-2) [    ] 
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> 1143023 INFO  (qtp234947723-20627) [n:127.0.0.1:60480_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={collection.configName=conf1&maxShardsPerNode=2&name=dv_coll&nrtReplicas=1&action=CREATE&numShards=4&wt=javabin&version=2}
 status=0 QTime=1785
   [junit4]   2> 1143023 INFO  (zkCallback-8012-thread-3) [    ] 
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> 1143027 INFO  (qtp1558870416-20642) [n:127.0.0.1:60481_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> 1143030 INFO  (qtp1558870416-20642) [n:127.0.0.1:60481_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> 1143032 INFO  (qtp1558870416-20642) [n:127.0.0.1:60481_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> 1143039 INFO  (qtp1558870416-20642) [n:127.0.0.1:60481_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> 1143039 INFO  (zkCallback-7997-thread-2) [    ] 
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> 1143039 INFO  (zkCallback-8012-thread-3) [    ] 
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> 1143039 INFO  (zkCallback-8012-thread-1) [    ] 
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> 1143039 INFO  (zkCallback-7997-thread-1) [    ] 
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> 1143040 INFO  (zkCallback-7997-thread-1) [    ] 
o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from ZooKeeper
   [junit4]   2> 1143040 INFO  (zkCallback-7997-thread-2) [    ] 
o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from ZooKeeper
   [junit4]   2> 1143040 INFO  (zkCallback-8012-thread-1) [    ] 
o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from ZooKeeper
   [junit4]   2> 1143040 INFO  (zkCallback-7997-thread-1) [    ] 
o.a.s.s.IndexSchema [dv_coll_shard2_replica_n2] Schema name=minimal
   [junit4]   2> 1143040 INFO  (zkCallback-7997-thread-2) [    ] 
o.a.s.s.IndexSchema [dv_coll_shard4_replica_n6] Schema name=minimal
   [junit4]   2> 1143042 INFO  (zkCallback-8012-thread-1) [    ] 
o.a.s.s.IndexSchema [dv_coll_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 1143046 INFO  (zkCallback-7997-thread-2) [    ] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1143046 INFO  (zkCallback-7997-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1143047 INFO  (Thread-2749) [    ] o.a.s.c.SolrCore config 
update listener called for core dv_coll_shard1_replica_n1
   [junit4]   2> 1143047 INFO  (Thread-2748) [    ] o.a.s.c.SolrCore config 
update listener called for core dv_coll_shard4_replica_n6
   [junit4]   2> 1143047 INFO  (zkCallback-8012-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1143047 INFO  (zkCallback-7997-thread-2) [    ] 
o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 7 ms
   [junit4]   2> 1143047 INFO  (zkCallback-7997-thread-1) [    ] 
o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 7 ms
   [junit4]   2> 1143047 INFO  (zkCallback-8012-thread-1) [    ] 
o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 6 ms
   [junit4]   2> 1143048 INFO  (Thread-2749) [    ] o.a.s.c.SolrCore core 
reload dv_coll_shard1_replica_n1
   [junit4]   2> 1143048 INFO  (Thread-2748) [    ] o.a.s.c.SolrCore core 
reload dv_coll_shard4_replica_n6
   [junit4]   2> 1143053 INFO  (qtp1558870416-20642) [n:127.0.0.1:60481_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 1143058 INFO  (Thread-2748) [    ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 9.0.0
   [junit4]   2> 1143060 INFO  (qtp1558870416-20642) [n:127.0.0.1:60481_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> 1143063 INFO  (Thread-2749) [    ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 9.0.0
   [junit4]   2> 1143064 INFO  (qtp1558870416-20642) [n:127.0.0.1:60481_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> 1143064 INFO  (qtp1558870416-20642) [n:127.0.0.1:60481_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> 1143069 INFO  (Thread-2748) [    ] o.a.s.s.IndexSchema 
[dv_coll_shard4_replica_n6] Schema name=minimal
   [junit4]   2> 1143072 INFO  (Thread-2748) [    ] o.a.s.s.IndexSchema Loaded 
schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1143072 INFO  (Thread-2748) [    ] o.a.s.c.CoreContainer 
Reloading SolrCore 'dv_coll_shard4_replica_n6' using configuration from 
collection dv_coll
   [junit4]   2> 1143076 INFO  (Thread-2749) [    ] o.a.s.s.IndexSchema 
[dv_coll_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 1143110 INFO  (Thread-2749) [    ] o.a.s.s.IndexSchema Loaded 
schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1143110 INFO  (Thread-2749) [    ] o.a.s.c.CoreContainer 
Reloading SolrCore 'dv_coll_shard1_replica_n1' using configuration from 
collection dv_coll
   [junit4]   2> 1143147 INFO  (qtp1558870416-20642) [n:127.0.0.1:60481_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_60481.solr.core.dv_coll.shard3.replica_n4' (registry 
'solr.core.dv_coll.shard3.replica_n4') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7d4347a9
   [junit4]   2> 1143147 INFO  (qtp1558870416-20642) [n:127.0.0.1:60481_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-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_36D6AADA7DA4CE1C-001\tempDir-001\node2\dv_coll_shard3_replica_n4],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_36D6AADA7DA4CE1C-001\tempDir-001\node2\.\dv_coll_shard3_replica_n4\data\]
   [junit4]   2> 1143150 INFO  (Thread-2748) [n:127.0.0.1:60480_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_60480.solr.core.dv_coll.shard4.replica_n6' (registry 
'solr.core.dv_coll.shard4.replica_n6') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7d4347a9
   [junit4]   2> 1143150 INFO  (Thread-2748) [n:127.0.0.1:60480_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-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_36D6AADA7DA4CE1C-001\tempDir-001\node1\dv_coll_shard4_replica_n6],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_36D6AADA7DA4CE1C-001\tempDir-001\node1\.\dv_coll_shard4_replica_n6\data\]
   [junit4]   2> 1143154 INFO  (Thread-2749) [n:127.0.0.1:60481_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_60481.solr.core.dv_coll.shard1.replica_n1' (registry 
'solr.core.dv_coll.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7d4347a9
   [junit4]   2> 1143154 INFO  (Thread-2749) [n:127.0.0.1:60481_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-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_36D6AADA7DA4CE1C-001\tempDir-001\node2\dv_coll_shard1_replica_n1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_36D6AADA7DA4CE1C-001\tempDir-001\node2\.\dv_coll_shard1_replica_n1\data\]
   [junit4]   2> 1143211 INFO  (Thread-2748) [n:127.0.0.1:60480_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> 1143211 INFO  (Thread-2748) [n:127.0.0.1:60480_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> 1143213 INFO  (Thread-2748) [n:127.0.0.1:60480_solr c:dv_coll 
s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@63f64d6a[dv_coll_shard4_replica_n6] main]
   [junit4]   2> 1143214 INFO  (Thread-2748) [n:127.0.0.1:60480_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> 1143215 INFO  (Thread-2748) [n:127.0.0.1:60480_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> 1143215 INFO  (Thread-2748) [n:127.0.0.1:60480_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> 1143215 INFO  (qtp1558870416-20642) [n:127.0.0.1:60481_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> 1143215 INFO  (qtp1558870416-20642) [n:127.0.0.1:60481_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> 1143215 INFO  (Thread-2748) [n:127.0.0.1:60480_solr c:dv_coll 
s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.s.ZkIndexSchemaReader 
Current schema version 1 is already the latest
   [junit4]   2> 1143215 INFO  (Thread-2748) [n:127.0.0.1:60480_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> 1143216 INFO  (Thread-2748) [n:127.0.0.1:60480_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 1628242494364844032
   [junit4]   2> 1143217 INFO  (qtp1558870416-20642) [n:127.0.0.1:60481_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@38ec99c0[dv_coll_shard3_replica_n4] 
main]
   [junit4]   2> 1143218 INFO  (Thread-2749) [n:127.0.0.1:60481_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> 1143218 INFO  (Thread-2749) [n:127.0.0.1:60481_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> 1143219 INFO  (qtp1558870416-20642) [n:127.0.0.1:60481_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> 1143219 INFO  
(searcherExecutor-6338-thread-1-processing-n:127.0.0.1:60480_solr 
x:dv_coll_shard4_replica_n6 c:dv_coll s:shard4 r:core_node8) 
[n:127.0.0.1:60480_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@63f64d6a[dv_coll_shard4_replica_n6] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1143219 INFO  (qtp1558870416-20642) [n:127.0.0.1:60481_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> 1143219 INFO  (qtp1558870416-20642) [n:127.0.0.1:60481_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> 1143220 INFO  (Thread-2749) [n:127.0.0.1:60481_solr c:dv_coll 
s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@8134620[dv_coll_shard1_replica_n1] main]
   [junit4]   2> 1143220 INFO  (qtp1558870416-20642) [n:127.0.0.1:60481_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> 1143220 INFO  (qtp1558870416-20642) [n:127.0.0.1:60481_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> 1143221 INFO  (qtp1558870416-20642) [n:127.0.0.1:60481_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 
1628242494370086912
   [junit4]   2> 1143221 INFO  (Thread-2749) [n:127.0.0.1:60481_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> 1143221 INFO  (Thread-2749) [n:127.0.0.1:60481_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> 1143221 INFO  (Thread-2749) [n:127.0.0.1:60481_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> 1143221 INFO  (Thread-2749) [n:127.0.0.1:60481_solr c:dv_coll 
s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader 
Current schema version 1 is already the latest
   [junit4]   2> 1143221 INFO  (Thread-2749) [n:127.0.0.1:60481_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> 1143223 INFO  (Thread-2749) [n:127.0.0.1:60481_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 1628242494372184064
   [junit4]   2> 1143223 INFO  
(searcherExecutor-6337-thread-1-processing-n:127.0.0.1:60481_solr 
x:dv_coll_shard3_replica_n4 c:dv_coll s:shard3 r:core_node7) 
[n:127.0.0.1:60481_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@38ec99c0[dv_coll_shard3_replica_n4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1143224 INFO  (Thread-2748) [n:127.0.0.1:60480_solr c:dv_coll 
s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.DefaultSolrCoreState 
New IndexWriter is ready to be used.
   [junit4]   2> 1143224 INFO  (Thread-2748) [n:127.0.0.1:60480_solr c:dv_coll 
s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@31e6b1e0[dv_coll_shard4_replica_n6] main]
   [junit4]   2> 1143224 INFO  (Thread-2748) [n:127.0.0.1:60480_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]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@2f3e9df8
   [junit4]   2> 1143224 INFO  (Thread-2748) [n:127.0.0.1:60480_solr c:dv_coll 
s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.m.SolrMetricManager 
Closing metric reporters for registry=solr.core.dv_coll.shard4.replica_n6, 
tag=2f3e9df8
   [junit4]   2> 1143224 INFO  (Thread-2748) [n:127.0.0.1:60480_solr c:dv_coll 
s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.m.r.SolrJmxReporter 
Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2fc90f8e: 
rootName = solr_60480, domain = solr.core.dv_coll.shard4.replica_n6, service 
url = null, agent id = null] for registry solr.core.dv_coll.shard4.replica_n6 / 
com.codahale.metrics.MetricRegistry@6cb0d925
   [junit4]   2> 1143227 INFO  
(searcherExecutor-6338-thread-1-processing-n:127.0.0.1:60480_solr 
x:dv_coll_shard4_replica_n6 c:dv_coll s:shard4 r:core_node8) 
[n:127.0.0.1:60480_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@31e6b1e0[dv_coll_shard4_replica_n6] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1143228 INFO  
(searcherExecutor-6339-thread-1-processing-n:127.0.0.1:60481_solr 
x:dv_coll_shard1_replica_n1 c:dv_coll s:shard1 r:core_node3) 
[n:127.0.0.1:60481_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@8134620[dv_coll_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1143228 INFO  (qtp1558870416-20642) [n:127.0.0.1:60481_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> 1143228 INFO  (qtp1558870416-20642) [n:127.0.0.1:60481_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@64ed90e[dv_coll_shard3_replica_n4] 
main]
   [junit4]   2> 1143230 INFO  
(searcherExecutor-6337-thread-1-processing-n:127.0.0.1:60481_solr 
x:dv_coll_shard3_replica_n4 c:dv_coll s:shard3 r:core_node7) 
[n:127.0.0.1:60481_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@64ed90e[dv_coll_shard3_replica_n4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1143231 INFO  (zkCallback-8012-thread-3) [    ] 
o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from ZooKeeper
   [junit4]   2> 1143231 INFO  (Thread-2748) [n:127.0.0.1:60480_solr c:dv_coll 
s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.m.SolrMetricManager 
Closing metric reporters for registry=solr.collection.dv_coll.shard4.leader, 
tag=2f3e9df8
   [junit4]   2> 1143231 INFO  (Thread-2748) [n:127.0.0.1:60480_solr c:dv_coll 
s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.SolrCore config 
update listener called for core dv_coll_shard2_replica_n2
   [junit4]   2> 1143233 INFO  (qtp1558870416-20642) [n:127.0.0.1:60481_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> 1143233 INFO  (zkCallback-8012-thread-3) [    ] 
o.a.s.s.IndexSchema [dv_coll_shard3_replica_n4] Schema name=minimal
   [junit4]   2> 1143237 INFO  (Thread-2749) [n:127.0.0.1:60481_solr c:dv_coll 
s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.DefaultSolrCoreState 
New IndexWriter is ready to be used.
   [junit4]   2> 1143237 INFO  (zkCallback-8012-thread-3) [    ] 
o.a.s.s.IndexSchema Loaded schema minimal/1.

[...truncated too long message...]

MetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 1402580 INFO  
(TEST-TestReplicationHandler.doTestStressReplication-seed#[36D6AADA7DA4CE1C]) [ 
   ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@4d21de16: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@494319b0
   [junit4]   2> 1402580 INFO  
(TEST-TestReplicationHandler.doTestStressReplication-seed#[36D6AADA7DA4CE1C]) [ 
   ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jetty, tag=null
   [junit4]   2> 1402580 INFO  
(TEST-TestReplicationHandler.doTestStressReplication-seed#[36D6AADA7DA4CE1C]) [ 
   ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@68964591: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@7d295d86
   [junit4]   2> 1402582 INFO  
(TEST-TestReplicationHandler.doTestStressReplication-seed#[36D6AADA7DA4CE1C]) [ 
   ] o.e.j.s.AbstractConnector Stopped 
ServerConnector@76bf24a0{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
   [junit4]   2> 1402582 INFO  
(TEST-TestReplicationHandler.doTestStressReplication-seed#[36D6AADA7DA4CE1C]) [ 
   ] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@7cb6a0b7{/solr,null,UNAVAILABLE}
   [junit4]   2> 1402582 INFO  
(TEST-TestReplicationHandler.doTestStressReplication-seed#[36D6AADA7DA4CE1C]) [ 
   ] o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 1402584 INFO  
(SUITE-TestReplicationHandler-seed#[36D6AADA7DA4CE1C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> 1402585 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    ] 
o.a.s.c.DirectoryFactory Deleted old index directory: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_36D6AADA7DA4CE1C-001\solr-instance-042\.\collection1\data\index.20190317090146470
   [junit4]   2> 1402587 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    ] 
o.a.s.c.DirectoryFactory Deleted old index directory: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_36D6AADA7DA4CE1C-001\solr-instance-042\.\collection1\data\index.20190317090146277
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene80), 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@5d7fb855),
 locale=en-VI, timezone=Etc/Greenwich
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 12 
(64-bit)/cpus=3,threads=1,free=202338952,total=314572800
   [junit4]   2> NOTE: All tests run in this JVM: [ShardsWhitelistTest, 
TestNumericTerms64, GraphQueryTest, LeaderElectionContextKeyTest, 
SyncSliceTest, TestSolrCLIRunExample, CoreMergeIndexesAdminHandlerTest, 
TestImplicitCoreProperties, HdfsChaosMonkeySafeLeaderTest, 
TestSortByMinMaxFunction, TestEmbeddedSolrServerConstructors, 
TestSolrQueryParser, TestTolerantSearch, TestQuerySenderNoQuery, BJQParserTest, 
CoreAdminCreateDiscoverTest, RestoreTriggerStateTest, 
FieldMutatingUpdateProcessorTest, CdcrVersionReplicationTest, 
HdfsCollectionsAPIDistributedZkTest, TestSolrConfigHandlerConcurrent, 
TestSchemaVersionResource, CopyFieldTest, TestLocalFSCloudBackupRestore, 
UUIDUpdateProcessorFallbackTest, SolrRrdBackendFactoryTest, TestLogWatcher, 
TestJsonFacetsStatsParsing, IndexSizeTriggerTest, TestFieldSortValues, 
QueryElevationComponentTest, TestReplicationHandlerDiskOverFlow, 
SolrCmdDistributorTest, HdfsAutoAddReplicasIntegrationTest, SuggesterTSTTest, 
TestCloudRecovery, OverriddenZkACLAndCredentialsProvidersTest, 
NoCacheHeaderTest, TestIndexSearcher, TestCopyFieldCollectionResource, 
DistributedFacetPivotLongTailTest, TermVectorComponentDistributedTest, 
TestPKIAuthenticationPlugin, TestSlowCompositeReaderWrapper, 
TestManagedResource, TestRecoveryHdfs, TimeZoneUtilsTest, TestFiltering, 
CloneFieldUpdateProcessorFactoryTest, TestInitParams, TestFacetMethods, 
TestPullReplica, MultiSolrCloudTestCaseTest, TestFileDictionaryLookup, 
IndexSchemaTest, TestCloudInspectUtil, IndexBasedSpellCheckerTest, 
TestPostingsSolrHighlighter, TestDocTermOrds, TestStressLiveNodes, 
InfixSuggestersTest, TestUpdate, TestConfigSetsAPI, SortSpecParsingTest, 
TriLevelCompositeIdRoutingTest, TestRebalanceLeaders, 
TestOnReconnectListenerSupport, BlobRepositoryCloudTest, 
TestNonDefinedSimilarityFactory, BasicFunctionalityTest, TestLegacyFieldCache, 
TestDistributedMap, ZkControllerTest, TestScoreJoinQPScore, 
ConnectionManagerTest, DistributedMLTComponentTest, TestSearcherReuse, 
TestSolrConfigHandler, SuggesterWFSTTest, SecurityConfHandlerTest, 
TestDocBasedVersionConstraints, TestLegacyField, OverseerTest, 
RestartWhileUpdatingTest, TestJavabinTupleStreamParser, 
CollectionsAPIAsyncDistributedZkTest, BadComponentTest, TestCloudConsistency, 
TolerantUpdateProcessorTest, TestSimClusterStateProvider, SortByFunctionTest, 
SolrJmxReporterTest, HdfsDirectoryTest, TestCursorMarkWithoutUniqueKey, 
Tagger2Test, ActionThrottleTest, TestDynamicFieldResource, TestExpandComponent, 
TestDocumentBuilder, TestStressCloudBlindAtomicUpdates, TestLockTree, 
TestSurroundQueryParser, TestIndexingPerformance, TestZkAclsWithHadoopAuth, 
CdcrReplicationHandlerTest, CursorPagingTest, TestBM25SimilarityFactory, 
QueryParsingTest, TestDocSet, TestSimPolicyCloud, TestReloadDeadlock, 
MergeStrategyTest, TestJettySolrRunner, 
DistribDocExpirationUpdateProcessorTest, DocValuesTest, SystemInfoHandlerTest, 
SimpleCollectionCreateDeleteTest, ByteBuffersDirectoryFactoryTest, 
DeleteStatusTest, TestShortCircuitedRequests, TestImpersonationWithHadoopAuth, 
RemoteQueryErrorTest, SOLR749Test, TestSolrCoreSnapshots, 
TestPerFieldSimilarityWithDefaultOverride, ZkSolrClientTest, 
TestRandomDVFaceting, TestManagedSynonymFilterFactory, CursorMarkTest, 
HighlighterMaxOffsetTest, TestRestoreCore, TestSolrQueryResponse, 
PeerSyncWithBufferUpdatesTest, UpdateRequestProcessorFactoryTest, 
ConcurrentDeleteAndCreateCollectionTest, DirectoryFactoryTest, TestStreamBody, 
ClusterStateUpdateTest, TestExclusionRuleCollectionAccess, 
TestBulkSchemaConcurrent, TestExactStatsCache, TestHighlightDedupGrouping, 
SolrPluginUtilsTest, RequestLoggingTest, WrapperMergePolicyFactoryTest, 
SolrRequestParserTest, TestUseDocValuesAsStored, 
ClassificationUpdateProcessorIntegrationTest, UUIDFieldTest, 
TestPhraseSuggestions, TestBadConfig, TestConfigSetImmutable, 
TestCloudPivotFacet, TestRTimerTree, TestNestedDocsSort, 
TestLuceneMatchVersion, TestMinMaxOnMultiValuedField, TestTestInjection, 
DistributedIntervalFacetingTest, BasicDistributedZkTest, 
ExternalFileFieldSortTest, TestSkipOverseerOperations, TestCSVResponseWriter, 
DirectSolrSpellCheckerTest, SpellCheckComponentTest, TestConfigSets, 
DeleteLastCustomShardedReplicaTest, FastVectorHighlighterTest, 
DistributedUpdateProcessorTest, TestFieldTypeResource, 
EmbeddedSolrNoSerializeTest, MinimalSchemaTest, SchemaApiFailureTest, 
TestMultiWordSynonyms, TestSSLRandomization, 
TestLeaderElectionWithEmptyReplica, DocValuesNotIndexedTest, 
TestSimNodeAddedTrigger, TestRandomFlRTGCloud, DateFieldTest, VersionInfoTest, 
TestUniqueKeyFieldResource, TestCloudSchemaless, AnalyticsQueryTest, 
MissingSegmentRecoveryTest, MetricTriggerTest, TestUtilizeNode, RulesTest, 
TestCollectionsAPIViaSolrCloudCluster, TestIBSimilarityFactory, 
TestReplicationHandler]
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestReplicationHandler -Dtests.seed=36D6AADA7DA4CE1C 
-Dtests.slow=true -Dtests.locale=en-VI -Dtests.timezone=Etc/Greenwich 
-Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J1 | TestReplicationHandler (suite) <<<
   [junit4]    > Throwable #1: java.io.IOException: Could not remove the 
following files (in the order of attempts):
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_36D6AADA7DA4CE1C-001\solr-instance-042\collection1\data\index.20190317090146277:
 java.nio.file.NoSuchFileException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_36D6AADA7DA4CE1C-001\solr-instance-042\collection1\data\index.20190317090146277
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([36D6AADA7DA4CE1C]:0)
   [junit4]    >        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:318)
   [junit4]    >        at java.base/java.lang.Thread.run(Thread.java:835)
   [junit4] Completed [351/847 (3!)] on J1 in 71.84s, 20 tests, 1 error <<< 
FAILURES!

[...truncated 44266 lines...]
[repro] Jenkins log URL: 
https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7792/consoleText

[repro] Revision: 5c143022e7abcdf14a570786afec4ff099fd581c

[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC"

[repro] ant clean

[...truncated 6 lines...]
[repro] Test suites by module:
[repro]    solr\core
[repro]       MissingSegmentRecoveryTest
[repro]       DocValuesNotIndexedTest
[repro]       TestReplicationHandler
[repro] ant compile-test

[...truncated 3592 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=15 
-Dtests.class="*.MissingSegmentRecoveryTest|*.DocValuesNotIndexedTest|*.TestReplicationHandler"
 -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC" 
-Dtests.seed=36D6AADA7DA4CE1C -Dtests.slow=true -Dtests.locale=rm 
-Dtests.timezone=Asia/Irkutsk -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII

[...truncated 223 lines...]
[repro] Failures:
[repro]   0/5 failed: org.apache.solr.cloud.DocValuesNotIndexedTest
[repro]   0/5 failed: org.apache.solr.cloud.MissingSegmentRecoveryTest
[repro]   0/5 failed: org.apache.solr.handler.TestReplicationHandler
[repro] Exiting with code 0

[...truncated 78 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to