Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-MacOSX/263/
Java: 64bit/jdk-9 -XX:-UseCompressedOops -XX:+UseParallelGC 
--illegal-access=deny

2 tests failed.
FAILED:  
org.apache.solr.cloud.CustomCollectionTest.testRouteFieldForImplicitRouter

Error Message:
Collection not found: withShardField

Stack Trace:
org.apache.solr.common.SolrException: Collection not found: withShardField
        at 
__randomizedtesting.SeedInfo.seed([1D207BC56E61C317:48709357C2980CE7]:0)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:834)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:800)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178)
        at 
org.apache.solr.client.solrj.request.UpdateRequest.commit(UpdateRequest.java:233)
        at 
org.apache.solr.cloud.CustomCollectionTest.testRouteFieldForImplicitRouter(CustomCollectionTest.java:141)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:564)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.base/java.lang.Thread.run(Thread.java:844)


FAILED:  org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingDVOnly

Error Message:
Unexpected number of elements in the group for intGSF: 6

Stack Trace:
java.lang.AssertionError: Unexpected number of elements in the group for 
intGSF: 6
        at 
__randomizedtesting.SeedInfo.seed([1D207BC56E61C317:869B159D2339F149]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at 
org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingDVOnly(DocValuesNotIndexedTest.java:377)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:564)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.base/java.lang.Thread.run(Thread.java:844)




Build Log:
[...truncated 11678 lines...]
   [junit4] Suite: org.apache.solr.cloud.DocValuesNotIndexedTest
   [junit4]   2> Creating dataDir: 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_1D207BC56E61C317-001/init-core-data-001
   [junit4]   2> 386708 WARN  
(SUITE-DocValuesNotIndexedTest-seed#[1D207BC56E61C317]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 386709 INFO  
(SUITE-DocValuesNotIndexedTest-seed#[1D207BC56E61C317]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 386710 INFO  
(SUITE-DocValuesNotIndexedTest-seed#[1D207BC56E61C317]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, 
clientAuth=0.0/0.0) w/ MAC_OS_X supressed clientAuth
   [junit4]   2> 386710 INFO  
(SUITE-DocValuesNotIndexedTest-seed#[1D207BC56E61C317]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 386711 INFO  
(SUITE-DocValuesNotIndexedTest-seed#[1D207BC56E61C317]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_1D207BC56E61C317-001/tempDir-001
   [junit4]   2> 386711 INFO  
(SUITE-DocValuesNotIndexedTest-seed#[1D207BC56E61C317]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 386711 INFO  (Thread-467) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 386711 INFO  (Thread-467) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 386714 ERROR (Thread-467) [    ] o.a.z.s.ZooKeeperServer 
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action 
on ERROR or SHUTDOWN server state changes
   [junit4]   2> 386817 INFO  
(SUITE-DocValuesNotIndexedTest-seed#[1D207BC56E61C317]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:50519
   [junit4]   2> 386837 INFO  (jetty-launcher-420-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 386837 INFO  (jetty-launcher-420-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 386840 INFO  (jetty-launcher-420-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@45b8043b{/solr,null,AVAILABLE}
   [junit4]   2> 386841 INFO  (jetty-launcher-420-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@4621307f{/solr,null,AVAILABLE}
   [junit4]   2> 386841 INFO  (jetty-launcher-420-thread-2) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@64b9b6a6{SSL,[ssl, 
http/1.1]}{127.0.0.1:50524}
   [junit4]   2> 386841 INFO  (jetty-launcher-420-thread-1) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@2b9d6aa3{SSL,[ssl, 
http/1.1]}{127.0.0.1:50525}
   [junit4]   2> 386841 INFO  (jetty-launcher-420-thread-2) [    ] 
o.e.j.s.Server Started @389931ms
   [junit4]   2> 386841 INFO  (jetty-launcher-420-thread-1) [    ] 
o.e.j.s.Server Started @389931ms
   [junit4]   2> 386841 INFO  (jetty-launcher-420-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=50524}
   [junit4]   2> 386841 INFO  (jetty-launcher-420-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=50525}
   [junit4]   2> 386841 ERROR (jetty-launcher-420-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 386841 ERROR (jetty-launcher-420-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 386842 INFO  (jetty-launcher-420-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.2.0
   [junit4]   2> 386842 INFO  (jetty-launcher-420-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 386842 INFO  (jetty-launcher-420-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.2.0
   [junit4]   2> 386842 INFO  (jetty-launcher-420-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 386842 INFO  (jetty-launcher-420-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 386842 INFO  (jetty-launcher-420-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 386842 INFO  (jetty-launcher-420-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-10-24T05:31:32.017304Z
   [junit4]   2> 386842 INFO  (jetty-launcher-420-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-10-24T05:31:32.017364Z
   [junit4]   2> 386846 INFO  (jetty-launcher-420-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 386846 INFO  (jetty-launcher-420-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 386858 INFO  (jetty-launcher-420-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50519/solr
   [junit4]   2> 386859 INFO  (jetty-launcher-420-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50519/solr
   [junit4]   2> 387000 INFO  (jetty-launcher-420-thread-2) [    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 387001 INFO  (jetty-launcher-420-thread-1) [    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 387002 INFO  (jetty-launcher-420-thread-2) [    ] 
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50524_solr
   [junit4]   2> 387002 INFO  (jetty-launcher-420-thread-1) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:50525_solr
   [junit4]   2> 387003 INFO  (jetty-launcher-420-thread-2) [    ] 
o.a.s.c.Overseer Overseer 
(id=98882230149971973-127.0.0.1:50524_solr-n_0000000000) starting
   [junit4]   2> 387004 INFO  (zkCallback-431-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 387005 INFO  (zkCallback-432-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 387016 INFO  (jetty-launcher-420-thread-2) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:50524_solr
   [junit4]   2> 387018 INFO  (zkCallback-431-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 387019 INFO  (zkCallback-432-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 387062 INFO  (jetty-launcher-420-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50525.solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f6ce7dd
   [junit4]   2> 387069 INFO  (jetty-launcher-420-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50525.solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f6ce7dd
   [junit4]   2> 387070 INFO  (jetty-launcher-420-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50525.solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f6ce7dd
   [junit4]   2> 387070 INFO  (jetty-launcher-420-thread-1) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_1D207BC56E61C317-001/tempDir-001/node1/.
   [junit4]   2> 387073 INFO  (jetty-launcher-420-thread-2) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50524.solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f6ce7dd
   [junit4]   2> 387092 INFO  (jetty-launcher-420-thread-2) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50524.solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f6ce7dd
   [junit4]   2> 387092 INFO  (jetty-launcher-420-thread-2) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50524.solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f6ce7dd
   [junit4]   2> 387093 INFO  (jetty-launcher-420-thread-2) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_1D207BC56E61C317-001/tempDir-001/node2/.
   [junit4]   2> 387116 INFO  
(SUITE-DocValuesNotIndexedTest-seed#[1D207BC56E61C317]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 387117 INFO  
(SUITE-DocValuesNotIndexedTest-seed#[1D207BC56E61C317]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50519/solr ready
   [junit4]   2> 387138 INFO  (qtp1238004669-2671) [    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf1&maxShardsPerNode=2&name=dv_coll&nrtReplicas=1&action=CREATE&numShards=4&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 387140 INFO  (OverseerThreadFactory-952-thread-1) [    ] 
o.a.s.c.CreateCollectionCmd Create collection dv_coll
   [junit4]   2> 387254 INFO  
(OverseerStateUpdate-98882230149971973-127.0.0.1:50524_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"dv_coll",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"dv_coll_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:50524/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 387257 INFO  
(OverseerStateUpdate-98882230149971973-127.0.0.1:50524_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"dv_coll",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"dv_coll_shard2_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:50525/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 387260 INFO  
(OverseerStateUpdate-98882230149971973-127.0.0.1:50524_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"dv_coll",
   [junit4]   2>   "shard":"shard3",
   [junit4]   2>   "core":"dv_coll_shard3_replica_n4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:50524/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 387262 INFO  
(OverseerStateUpdate-98882230149971973-127.0.0.1:50524_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"dv_coll",
   [junit4]   2>   "shard":"shard4",
   [junit4]   2>   "core":"dv_coll_shard4_replica_n6",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:50525/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 387481 INFO  (qtp1238004669-2667) [    ] 
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> 387481 INFO  (qtp1238004669-2667) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 387482 INFO  (qtp1238004669-2672) [    ] 
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> 387502 INFO  (qtp817567712-2679) [    ] 
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> 387502 INFO  (qtp817567712-2679) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 387504 INFO  (qtp817567712-2665) [    ] 
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> 387621 INFO  (zkCallback-432-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> 387622 INFO  (zkCallback-431-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> 387622 INFO  (zkCallback-432-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> 387623 INFO  (zkCallback-431-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> 388531 INFO  (qtp817567712-2665) [    ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 388532 INFO  (qtp1238004669-2667) [    ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 388541 INFO  (qtp817567712-2679) [    ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 388541 INFO  (qtp1238004669-2672) [    ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 388554 INFO  (qtp817567712-2665) [    ] o.a.s.s.IndexSchema 
[dv_coll_shard4_replica_n6] Schema name=minimal
   [junit4]   2> 388554 INFO  (qtp1238004669-2667) [    ] o.a.s.s.IndexSchema 
[dv_coll_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 388555 INFO  (qtp1238004669-2667) [    ] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 388555 INFO  (qtp1238004669-2667) [    ] o.a.s.c.CoreContainer 
Creating SolrCore 'dv_coll_shard1_replica_n1' using configuration from 
collection dv_coll, trusted=true
   [junit4]   2> 388555 INFO  (qtp817567712-2665) [    ] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 388556 INFO  (qtp817567712-2665) [    ] o.a.s.c.CoreContainer 
Creating SolrCore 'dv_coll_shard4_replica_n6' using configuration from 
collection dv_coll, trusted=true
   [junit4]   2> 388556 INFO  (qtp1238004669-2667) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50524.solr.core.dv_coll.shard1.replica_n1' (registry 
'solr.core.dv_coll.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f6ce7dd
   [junit4]   2> 388556 INFO  (qtp817567712-2665) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50525.solr.core.dv_coll.shard4.replica_n6' (registry 
'solr.core.dv_coll.shard4.replica_n6') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f6ce7dd
   [junit4]   2> 388556 INFO  (qtp1238004669-2667) [    ] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 388556 INFO  (qtp817567712-2665) [    ] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 388556 INFO  (qtp1238004669-2667) [    ] o.a.s.c.SolrCore 
[[dv_coll_shard1_replica_n1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_1D207BC56E61C317-001/tempDir-001/node2/dv_coll_shard1_replica_n1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_1D207BC56E61C317-001/tempDir-001/node2/./dv_coll_shard1_replica_n1/data/]
   [junit4]   2> 388556 INFO  (qtp817567712-2665) [    ] o.a.s.c.SolrCore 
[[dv_coll_shard4_replica_n6] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_1D207BC56E61C317-001/tempDir-001/node1/dv_coll_shard4_replica_n6],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_1D207BC56E61C317-001/tempDir-001/node1/./dv_coll_shard4_replica_n6/data/]
   [junit4]   2> 388559 INFO  (qtp817567712-2679) [    ] o.a.s.s.IndexSchema 
[dv_coll_shard2_replica_n2] Schema name=minimal
   [junit4]   2> 388559 INFO  (qtp1238004669-2672) [    ] o.a.s.s.IndexSchema 
[dv_coll_shard3_replica_n4] Schema name=minimal
   [junit4]   2> 388561 INFO  (qtp1238004669-2672) [    ] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 388561 INFO  (qtp1238004669-2672) [    ] o.a.s.c.CoreContainer 
Creating SolrCore 'dv_coll_shard3_replica_n4' using configuration from 
collection dv_coll, trusted=true
   [junit4]   2> 388561 INFO  (qtp817567712-2679) [    ] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 388561 INFO  (qtp1238004669-2672) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50524.solr.core.dv_coll.shard3.replica_n4' (registry 
'solr.core.dv_coll.shard3.replica_n4') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f6ce7dd
   [junit4]   2> 388561 INFO  (qtp817567712-2679) [    ] o.a.s.c.CoreContainer 
Creating SolrCore 'dv_coll_shard2_replica_n2' using configuration from 
collection dv_coll, trusted=true
   [junit4]   2> 388562 INFO  (qtp1238004669-2672) [    ] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 388562 INFO  (qtp1238004669-2672) [    ] o.a.s.c.SolrCore 
[[dv_coll_shard3_replica_n4] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_1D207BC56E61C317-001/tempDir-001/node2/dv_coll_shard3_replica_n4],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_1D207BC56E61C317-001/tempDir-001/node2/./dv_coll_shard3_replica_n4/data/]
   [junit4]   2> 388562 INFO  (qtp817567712-2679) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50525.solr.core.dv_coll.shard2.replica_n2' (registry 
'solr.core.dv_coll.shard2.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f6ce7dd
   [junit4]   2> 388562 INFO  (qtp817567712-2679) [    ] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 388562 INFO  (qtp817567712-2679) [    ] o.a.s.c.SolrCore 
[[dv_coll_shard2_replica_n2] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_1D207BC56E61C317-001/tempDir-001/node1/dv_coll_shard2_replica_n2],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_1D207BC56E61C317-001/tempDir-001/node1/./dv_coll_shard2_replica_n2/data/]
   [junit4]   2> 388618 INFO  (qtp1238004669-2672) [    ] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 388618 INFO  (qtp1238004669-2672) [    ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 388619 INFO  (qtp1238004669-2672) [    ] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 388619 INFO  (qtp1238004669-2672) [    ] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 388622 INFO  (qtp817567712-2665) [    ] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 388622 INFO  (qtp817567712-2665) [    ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 388623 INFO  (qtp817567712-2665) [    ] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 388623 INFO  (qtp817567712-2665) [    ] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 388628 INFO  (qtp1238004669-2667) [    ] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 388628 INFO  (qtp1238004669-2667) [    ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 388629 INFO  (qtp1238004669-2667) [    ] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 388629 INFO  (qtp1238004669-2667) [    ] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 388631 INFO  (qtp1238004669-2672) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@ce0e697[dv_coll_shard3_replica_n4] 
main]
   [junit4]   2> 388632 INFO  (qtp1238004669-2672) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 388633 INFO  (qtp1238004669-2672) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 388633 INFO  (qtp1238004669-2672) [    ] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/conf1/managed-schema
   [junit4]   2> 388633 INFO  (qtp1238004669-2672) [    ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 388634 INFO  (qtp1238004669-2672) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 388634 INFO  (qtp1238004669-2672) [    ] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1582115684413865984
   [junit4]   2> 388636 INFO  (searcherExecutor-959-thread-1) [    ] 
o.a.s.c.SolrCore [dv_coll_shard3_replica_n4] Registered new searcher 
Searcher@ce0e697[dv_coll_shard3_replica_n4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 388639 INFO  (qtp1238004669-2667) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@769af3c3[dv_coll_shard1_replica_n1] 
main]
   [junit4]   2> 388639 INFO  (qtp817567712-2679) [    ] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 388639 INFO  (qtp817567712-2679) [    ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 388640 INFO  (qtp1238004669-2672) [    ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 388640 INFO  (qtp1238004669-2672) [    ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 388640 INFO  (qtp1238004669-2672) [    ] o.a.s.c.SyncStrategy 
Sync replicas to https://127.0.0.1:50524/solr/dv_coll_shard3_replica_n4/
   [junit4]   2> 388640 INFO  (qtp1238004669-2672) [    ] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 388640 INFO  (qtp1238004669-2667) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 388640 INFO  (qtp1238004669-2672) [    ] o.a.s.c.SyncStrategy 
https://127.0.0.1:50524/solr/dv_coll_shard3_replica_n4/ has no replicas
   [junit4]   2> 388640 INFO  (qtp1238004669-2672) [    ] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 388640 INFO  (qtp817567712-2679) [    ] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 388641 INFO  (qtp817567712-2679) [    ] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 388641 INFO  (qtp817567712-2665) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7fe03112[dv_coll_shard4_replica_n6] 
main]
   [junit4]   2> 388641 INFO  (qtp1238004669-2667) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 388641 INFO  (qtp1238004669-2667) [    ] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/conf1/managed-schema
   [junit4]   2> 388642 INFO  (qtp1238004669-2667) [    ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 388642 INFO  (qtp1238004669-2667) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 388642 INFO  (qtp817567712-2679) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4954a4ff[dv_coll_shard2_replica_n2] 
main]
   [junit4]   2> 388642 INFO  (qtp1238004669-2667) [    ] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1582115684422254592
   [junit4]   2> 388643 INFO  (searcherExecutor-957-thread-1) [    ] 
o.a.s.c.SolrCore [dv_coll_shard1_replica_n1] Registered new searcher 
Searcher@769af3c3[dv_coll_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 388644 INFO  (qtp817567712-2679) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 388644 INFO  (qtp817567712-2679) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 388644 INFO  (qtp817567712-2679) [    ] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/conf1/managed-schema
   [junit4]   2> 388645 INFO  (qtp817567712-2665) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 388645 INFO  (qtp817567712-2679) [    ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 388645 INFO  (qtp1238004669-2672) [    ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:50524/solr/dv_coll_shard3_replica_n4/ shard3
   [junit4]   2> 388645 INFO  (qtp817567712-2679) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 388646 INFO  (qtp817567712-2679) [    ] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1582115684426448896
   [junit4]   2> 388646 INFO  (searcherExecutor-960-thread-1) [    ] 
o.a.s.c.SolrCore [dv_coll_shard2_replica_n2] Registered new searcher 
Searcher@4954a4ff[dv_coll_shard2_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 388646 INFO  (qtp817567712-2665) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 388647 INFO  (qtp817567712-2665) [    ] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/conf1/managed-schema
   [junit4]   2> 388648 INFO  (qtp817567712-2665) [    ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 388648 INFO  (qtp1238004669-2667) [    ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 388648 INFO  (qtp1238004669-2667) [    ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 388648 INFO  (qtp1238004669-2667) [    ] o.a.s.c.SyncStrategy 
Sync replicas to https://127.0.0.1:50524/solr/dv_coll_shard1_replica_n1/
   [junit4]   2> 388648 INFO  (qtp1238004669-2667) [    ] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 388648 INFO  (qtp1238004669-2667) [    ] o.a.s.c.SyncStrategy 
https://127.0.0.1:50524/solr/dv_coll_shard1_replica_n1/ has no replicas
   [junit4]   2> 388648 INFO  (qtp1238004669-2667) [    ] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 388649 INFO  (qtp817567712-2665) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 388649 INFO  (searcherExecutor-958-thread-1) [    ] 
o.a.s.c.SolrCore [dv_coll_shard4_replica_n6] Registered new searcher 
Searcher@7fe03112[dv_coll_shard4_replica_n6] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 388650 INFO  (qtp817567712-2665) [    ] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1582115684430643200
   [junit4]   2> 388651 INFO  (qtp817567712-2679) [    ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 388651 INFO  (qtp817567712-2679) [    ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 388651 INFO  (qtp817567712-2679) [    ] o.a.s.c.SyncStrategy 
Sync replicas to https://127.0.0.1:50525/solr/dv_coll_shard2_replica_n2/
   [junit4]   2> 388651 INFO  (qtp817567712-2679) [    ] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 388651 INFO  (qtp817567712-2679) [    ] o.a.s.c.SyncStrategy 
https://127.0.0.1:50525/solr/dv_coll_shard2_replica_n2/ has no replicas
   [junit4]   2> 388651 INFO  (qtp817567712-2679) [    ] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 388652 INFO  (qtp1238004669-2667) [    ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:50524/solr/dv_coll_shard1_replica_n1/ shard1
   [junit4]   2> 388655 INFO  (qtp817567712-2679) [    ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:50525/solr/dv_coll_shard2_replica_n2/ shard2
   [junit4]   2> 388657 INFO  (qtp817567712-2665) [    ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 388657 INFO  (qtp817567712-2665) [    ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 388657 INFO  (qtp817567712-2665) [    ] o.a.s.c.SyncStrategy 
Sync replicas to https://127.0.0.1:50525/solr/dv_coll_shard4_replica_n6/
   [junit4]   2> 388657 INFO  (qtp817567712-2665) [    ] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 388657 INFO  (qtp817567712-2665) [    ] o.a.s.c.SyncStrategy 
https://127.0.0.1:50525/solr/dv_coll_shard4_replica_n6/ has no replicas
   [junit4]   2> 388657 INFO  (qtp817567712-2665) [    ] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 388661 INFO  (qtp817567712-2665) [    ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:50525/solr/dv_coll_shard4_replica_n6/ shard4
   [junit4]   2> 388775 INFO  (zkCallback-432-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> 388775 INFO  (zkCallback-432-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> 388775 INFO  (zkCallback-431-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> 388775 INFO  (zkCallback-431-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> 388814 INFO  (qtp1238004669-2672) [    ] o.a.s.c.ZkController 
I am the leader, no recovery necessary
   [junit4]   2> 388814 INFO  (qtp817567712-2679) [    ] o.a.s.c.ZkController I 
am the leader, no recovery necessary
   [junit4]   2> 388814 INFO  (qtp1238004669-2667) [    ] o.a.s.c.ZkController 
I am the leader, no recovery necessary
   [junit4]   2> 388817 INFO  (qtp1238004669-2672) [    ] 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=1335
   [junit4]   2> 388818 INFO  (qtp817567712-2679) [    ] 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=1316
   [junit4]   2> 388819 INFO  (qtp1238004669-2667) [    ] 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=1338
   [junit4]   2> 388826 INFO  (qtp817567712-2665) [    ] o.a.s.c.ZkController I 
am the leader, no recovery necessary
   [junit4]   2> 388829 INFO  (qtp817567712-2665) [    ] 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=1325
   [junit4]   2> 388836 INFO  (qtp1238004669-2671) [    ] 
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
30 seconds. Check all shard replicas
   [junit4]   2> 388942 INFO  (zkCallback-432-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> 388942 INFO  (zkCallback-431-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> 388942 INFO  (zkCallback-431-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> 388942 INFO  (zkCallback-432-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> 389153 INFO  
(OverseerCollectionConfigSetProcessor-98882230149971973-127.0.0.1:50524_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 389847 INFO  (qtp1238004669-2671) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/collections 
params={replicationFactor=1&collection.configName=conf1&maxShardsPerNode=2&name=dv_coll&nrtReplicas=1&action=CREATE&numShards=4&wt=javabin&version=2}
 status=0 QTime=2709
   [junit4]   2> 389859 INFO  (qtp1238004669-2669) [    ] o.a.s.s.SchemaManager 
managed schema loaded . version : 0 
   [junit4]   2> 389860 INFO  (qtp1238004669-2669) [    ] o.a.s.s.IndexSchema 
[dv_coll_shard3_replica_n4] Schema name=minimal
   [junit4]   2> 389862 INFO  (qtp1238004669-2669) [    ] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 389870 INFO  (qtp1238004669-2669) [    ] o.a.s.c.ZkController 
Persisted config data to node /configs/conf1/managed-schema 
   [junit4]   2> 389870 INFO  (zkCallback-431-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> 389870 INFO  (zkCallback-432-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> 389870 INFO  (zkCallback-431-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> 389870 INFO  (zkCallback-432-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> 389871 INFO  (zkCallback-432-thread-2) [    ] 
o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from ZooKeeper
   [junit4]   2> 389872 INFO  (zkCallback-432-thread-1) [    ] 
o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from ZooKeeper
   [junit4]   2> 389873 INFO  (zkCallback-432-thread-1) [    ] 
o.a.s.s.IndexSchema [dv_coll_shard2_replica_n2] Schema name=minimal
   [junit4]   2> 389873 INFO  (zkCallback-432-thread-2) [    ] 
o.a.s.s.IndexSchema [dv_coll_shard4_replica_n6] Schema name=minimal
   [junit4]   2> 389877 INFO  (zkCallback-432-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 389877 INFO  (zkCallback-431-thread-3) [    ] 
o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from ZooKeeper
   [junit4]   2> 389878 INFO  (zkCallback-432-thread-2) [    ] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 389878 INFO  (zkCallback-431-thread-3) [    ] 
o.a.s.s.IndexSchema [dv_coll_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 389879 INFO  (zkCallback-432-thread-1) [    ] 
o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 7 ms
   [junit4]   2> 389879 INFO  (zkCallback-432-thread-2) [    ] 
o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 8 ms
   [junit4]   2> 389881 INFO  (Thread-486) [    ] o.a.s.c.SolrCore config 
update listener called for core dv_coll_shard1_replica_n1
   [junit4]   2> 389882 INFO  (Thread-487) [    ] o.a.s.c.SolrCore config 
update listener called for core dv_coll_shard4_replica_n6
   [junit4]   2> 389883 INFO  (zkCallback-431-thread-3) [    ] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 389883 INFO  (zkCallback-431-thread-3) [    ] 
o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 6 ms
   [junit4]   2> 389885 INFO  (Thread-486) [    ] o.a.s.c.SolrCore core reload 
dv_coll_shard1_replica_n1
   [junit4]   2> 389885 INFO  (Thread-487) [    ] o.a.s.c.SolrCore config 
update listener called for core dv_coll_shard2_replica_n2
   [junit4]   2> 389892 INFO  (qtp1238004669-2669) [    ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 389907 INFO  (Thread-486) [    ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 7.2.0
   [junit4]   2> 389911 INFO  (qtp1238004669-2669) [    ] o.a.s.s.IndexSchema 
[dv_coll_shard3_replica_n4] Schema name=minimal
   [junit4]   2> 389914 INFO  (Thread-486) [    ] o.a.s.s.IndexSchema 
[dv_coll_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 389914 INFO  (qtp1238004669-2669) [    ] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 389914 INFO  (qtp1238004669-2669) [    ] o.a.s.c.CoreContainer 
Reloading SolrCore 'dv_coll_shard3_replica_n4' using configuration from 
collection dv_coll
   [junit4]   2> 389918 INFO  (Thread-486) [    ] o.a.s.s.IndexSchema Loaded 
schema minimal/1.1 with uniqueid field id
   [junit4]   2> 389918 INFO  (Thread-486) [    ] o.a.s.c.CoreContainer 
Reloading SolrCore 'dv_coll_shard1_replica_n1' using configuration from 
collection dv_coll
   [junit4]   2> 389941 INFO  (Thread-486) [    ] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_50524.solr.core.dv_coll.shard1.replica_n1' (registry 
'solr.core.dv_coll.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f6ce7dd
   [junit4]   2> 389941 INFO  (Thread-486) [    ] o.a.s.c.SolrCore 
[[dv_coll_shard1_replica_n1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_1D207BC56E61C317-001/tempDir-001/node2/dv_coll_shard1_replica_n1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_1D207BC56E61C317-001/tempDir-001/node2/./dv_coll_shard1_replica_n1/data/]
   [junit4]   2> 389946 INFO  (qtp1238004669-2669) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50524.solr.core.dv_coll.shard3.replica_n4' (registry 
'solr.core.dv_coll.shard3.replica_n4') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f6ce7dd
   [junit4]   2> 389946 INFO  (qtp1238004669-2669) [    ] o.a.s.c.SolrCore 
[[dv_coll_shard3_replica_n4] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_1D207BC56E61C317-001/tempDir-001/node2/dv_coll_shard3_replica_n4],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_1D207BC56E61C317-001/tempDir-001/node2/./dv_coll_shard3_replica_n4/data/]
   [junit4]   2> 390158 INFO  (Thread-486) [    ] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 390158 INFO  (Thread-486) [    ] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 390161 INFO  (Thread-486) [    ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@259ed3d9[dv_coll_shard1_replica_n1] main]
   [junit4]   2> 390162 INFO  (Thread-486) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 390163 INFO  (Thread-486) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 390163 INFO  (Thread-486) [    ] o.a.s.s.ZkIndexSchemaReader 
Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
   [junit4]   2> 390163 INFO  (Thread-486) [    ] o.a.s.s.ZkIndexSchemaReader 
Current schema version 1 is already the latest
   [junit4]   2> 390164 INFO  (Thread-486) [    ] o.a.s.h.ReplicationHandler 
Commits will be reserved for 10000ms.
   [junit4]   2> 390165 INFO  (searcherExecutor-978-thread-1) [    ] 
o.a.s.c.SolrCore [dv_coll_shard1_replica_n1] Registered new searcher 
Searcher@259ed3d9[dv_coll_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 390165 INFO  (Thread-486) [    ] o.a.s.u.UpdateLog Could not 
find max version in index or recent updates, using new clock 1582115686019235840
   [junit4]   2> 390168 INFO  (Thread-486) [    ] o.a.s.u.DefaultSolrCoreState 
New IndexWriter is ready to be used.
   [junit4]   2> 390168 INFO  (Thread-486) [    ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@3d35f9cf[dv_coll_shard1_replica_n1] main]
   [junit4]   2> 390168 INFO  (Thread-486) [    ] o.a.s.c.SolrCore 
[dv_coll_shard1_replica_n1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@4511761b
   [junit4]   2> 390168 INFO  (Thread-486) [    ] o.a.s.m.SolrMetricManager 
Closing metric reporters for registry=solr.core.dv_coll.shard1.replica_n1, 
tag=1158772251
   [junit4]   2> 390168 INFO  (Thread-486) [    ] o.a.s.m.r.SolrJmxReporter 
Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@25dfa336: 
rootName = solr_50524, domain = solr.core.dv_coll.shard1.replica_n1, service 
url = null, agent id = null] for registry solr.core.dv_coll.shard1.replica_n1 / 
com.codahale.metrics.MetricRegistry@2e47e9ab
   [junit4]   2> 390176 INFO  (searcherExecutor-978-thread-1) [    ] 
o.a.s.c.SolrCore [dv_coll_shard1_replica_n1] Registered new searcher 
Searcher@3d35f9cf[dv_coll_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 390177 INFO  (qtp1238004669-2669) [    ] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 390177 INFO  (qtp1238004669-2669) [    ] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 390178 INFO  (Thread-486) [    ] o.a.s.m.SolrMetricManager 
Closing metric reporters for registry=solr.collection.dv_coll.shard1.leader, 
tag=1158772251
   [junit4]   2> 390178 INFO  (Thread-486) [    ] o.a.s.c.SolrCore config 
update listener called for core dv_coll_shard3_replica_n4
   [junit4]   2> 390180 INFO  (qtp1238004669-2669) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@2eb6a678[dv_coll_shard3_replica_n4] 
main]
   [junit4]   2> 390181 INFO  (Thread-486) [    ] o.a.s.c.SolrCore core reload 
dv_coll_shard3_replica_n4
   [junit4]   2> 390181 INFO  (qtp1238004669-2669) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 390182 INFO  (qtp1238004669-2669) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 390182 INFO  (qtp1238004669-2669) [    ] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/conf1/managed-schema
   [junit4]   2> 390182 INFO  (qtp1238004669-2669) [    ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 1 is already the latest
   [junit4]   2> 390183 INFO  (qtp1238004669-2669) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 390183 INFO  (qtp1238004669-2669) [    ] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1582115686038110208
   [junit4]   2> 390184 INFO  (searcherExecutor-977-thread-1) [    ] 
o.a.s.c.SolrCore [dv_coll_shard3_replica_n4] Registered new searcher 
Searcher@2eb6a678[dv_coll_shard3_replica_n4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 390185 INFO  (qtp1238004669-2669) [    ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 390185 INFO  (qtp1238004669-2669) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@bf60d[dv_coll_shard3_replica_n4] 
main]
   [junit4]   2> 390187 INFO  (qtp1238004669-2669) [    ] 
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> 390187 INFO  (searcherExecutor-977-thread-1) [    ] 
o.a.s.c.SolrCore [dv_coll_shard3_replica_n4] Registered new searcher 
Searcher@bf60d[dv_coll_shard3_replica_n4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 390187 INFO  (zkCallback-431-thread-2) [    ] 
o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from ZooKeeper
   [junit4]   2> 390189 INFO  (zkCallback-431-thread-2) [    ] 
o.a.s.s.IndexSchema [dv_coll_shard3_replica_n4] Schema name=minimal
   [junit4]   2> 390191 INFO  (zkCallback-431-thread-2) [    ] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 390191 INFO  (zkCallback-431-thread-2) [    ] 
o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 3 ms
   [junit4]   2> 390199 INFO  (qtp817567712-2666) [    ] o.a.s.c.S.Request 
[dv_coll_shard2_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 390200 INFO  (qtp817567712-2676) [    ] o.a.s.c.S.Request 
[dv_coll_shard4_replica_n6]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 390201 INFO  (qtp1238004669-2670) [    ] o.a.s.c.S.Request 
[dv_coll_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 390203 INFO  (qtp1238004669-2669) [    ] 
o.a.s.s.ManagedIndexSchema Took 17.0ms for 3 replicas to apply schema update 
version 1 for collection dv_coll
   [junit4]   2> 390203 INFO  (qtp1238004669-2669) [    ] o.a.s.c.S.Request 
[dv_coll_shard3_replica_n4]  webapp=/solr path=/schema 
params={wt=javabin&version=2} status=0 QTime=345
   [junit4]   2> 390203 INFO  (qtp1238004669-2669) [    ] o.a.s.c.SolrCore 
[dv_coll_shard3_replica_n4]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@3a2524e6
   [junit4]   2> 390203 INFO  (qtp1238004669-2669) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.dv_coll.shard3.replica_n4, tag=975512806
   [junit4]   2> 390203 INFO  (qtp1238004669-2669) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@4f10c183: rootName = 
solr_50524, domain = solr.core.dv_coll.shard3.replica_n4, service url = null, 
agent id = null] for registry solr.core.dv_coll.shard3.replica_n4 / 
com.codahale.metrics.MetricRegistry@7eac0390
   [junit4]   2> 390209 INFO  (qtp1238004669-2669) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection.dv_coll.shard3.leader, tag=975512806
   [junit4]   2> 390214 INFO  (Thread-486) [    ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 7.2.0
   [junit4]   2> 390234 INFO  
(TEST-DocValuesNotIndexedTest.testGroupingDVOnly-seed#[1D207BC56E61C317]) [    
] o.a.s.SolrTestCaseJ4 ###Starting testGroupingDVOnly
   [junit4]   2> 390237 INFO  (Thread-486) [    ] o.a.s.s.IndexSchema 
[dv_coll_shard3_replica_n4] Schema name=minimal
   [junit4]   2> 390240 INFO  (Thread-486) [    ] o.a.s.s.IndexSchema Loaded 
schema minimal/1.1 with uniqueid field id
   [junit4]   2> 390240 INFO  (Thread-486) [    ] o.a.s.c.CoreContainer 
Reloading SolrCore 'dv_coll_shard3_replica_n4' using configuration from 
collection dv_coll
   [junit4]   2> 390279 INFO  (Thread-486) [    ] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_50524.solr.core.dv_coll.shard3.replica_n4' (registry 
'solr.core.dv_coll.shard3.replica_n4') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f6ce7dd
   [junit4]   2> 390279 INFO  (Thread-486) [    ] o.a.s.c.SolrCore 
[[dv_coll_shard3_replica_n4] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_1D207BC56E61C317-001/tempDir-001/node2/dv_coll_shard3_replica_n4],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_1D207BC56E61C317-001/tempDir-001/node2/./dv_coll_shard3_replica_n4/data/]
   [junit4]   2> 390308 INFO  (qtp817567712-2666) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard4_replica_n6]  webapp=/solr 
path=/update 
params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:50524/solr/dv_coll_shard3_replica_n4/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1582115686102073344)} 0 64
   [junit4]   2> 390309 INFO  (qtp1238004669-2674) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica_n1]  webapp=/solr 
path=/update 
params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:50524/solr/dv_coll_shard3_replica_n4/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1582115686120947712)} 0 47
   [junit4]   2> 390310 INFO  (qtp817567712-2663) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard2_replica_n2]  webapp=/solr 
path=/update 
params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:50524/solr/dv_coll_shard3_replica_n4/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1582115686101024768)} 0 67
   [junit4]   2> 390310 INFO  (qtp1238004669-2670) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica_n4]  webapp=/solr 
path=/update params={wt=javabin&version=2}{deleteByQuery=*:* 
(-1582115686097879040)} 0 76
   [junit4]   2> 390318 INFO  (qtp1238004669-2667) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1582115686179667968,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 390318 INFO  (qtp1238004669-2667) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 390319 INFO  (qtp817567712-2676) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1582115686180716544,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 390319 INFO  (qtp817567712-2676) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 390319 INFO  (qtp817567712-2678) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1582115686180716544,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 390319 INFO  (qtp817567712-2678) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 390321 INFO  (qtp1238004669-2669) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1582115686182813696,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 390321 INFO  (qtp1238004669-2669) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 390322 INFO  (qtp1238004669-2667) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 390322 INFO  (qtp1238004669-2667) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica_n1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:50524/solr/dv_coll_shard3_replica_n4/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 3
   [junit4]   2> 390322 INFO  (qtp1238004669-2669) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 390323 INFO  (qtp817567712-2678) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@ac0dd08[dv_coll_shard2_replica_n2] 
main]
   [junit4]   2> 390323 INFO  (qtp817567712-2676) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@50cf8e1a[dv_coll_shard4_replica_n6] 
main]
   [junit4]   2> 390324 INFO  (qtp1238004669-2669) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica_n4]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:50524/solr/dv_coll_shard3_replica_n4/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 3
   [junit4]   2> 390324 INFO  (searcherExecutor-958-thread-1) [    ] 
o.a.s.c.SolrCore [dv_coll_shard4_replica_n6] Registered new searcher 
Searcher@50cf8e1a[dv_coll_shard4_replica_n6] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 390324 INFO  (searcherExecutor-960-thread-1) [    ] 
o.a.s.c.SolrCore [dv_coll_shard2_replica_n2] Registered new searcher 
Searcher@ac0dd08[dv_coll_shard2_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 390333 INFO  (qtp817567712-2676) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 390333 INFO  (qtp817567712-2678) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 390333 INFO  (qtp817567712-2676) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard4_replica_n6]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:50524/solr/dv_coll_shard3_replica_n4/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 14
   [junit4]   2> 390333 INFO  (qtp817567712-2678) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard2_replica_n2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:50524/solr/dv_coll_shard3_replica_n4/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 14
   [junit4]   2> 390335 INFO  (qtp1238004669-2672) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica_n4]  webapp=/solr 
path=/update 
params={_stateVer_=dv_coll:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 24
   [junit4]   2> 390338 INFO  (Thread-486) [    ] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 390338 INFO  (Thread-486) [    ] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 390340 INFO  (Thread-486) [    ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@55129a9d[dv_coll_shard3_replica_n4] main]
   [junit4]   2> 390346 INFO  (Thread-486) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 390346 INFO  (Thread-486) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 390346 INFO  (Thread-486) [    ] o.a.s.s.ZkIndexSchemaReader 
Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
   [junit4]   2> 390347 INFO  (Thread-486) [    ] o.a.s.s.ZkIndexSchemaReader 
Current schema version 1 is already the latest
   [junit4]   2> 390347 INFO  (Thread-486) [    ] o.a.s.h.ReplicationHandler 
Commits will be reserved for 10000ms.
   [junit4]   2> 390349 INFO  (searcherExecutor-986-thread-1) [    ] 
o.a.s.c.SolrCore [dv_coll_shard3_replica_n4] Registered new searcher 
Searcher@55129a9d[dv_coll_shard3_replica_n4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 390351 INFO  (qtp817567712-2666) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard4_replica_n6]  webapp=/solr 
path=/update params={_stateVer_=dv_coll:4&wt=javabin&version=2}{add=[10000 
(1582115686205882368), 5 (1582115686213222400), 17 (1582115686213222401), 19 
(1582115686213222402), 21 (1582115686214270976), 23 (1582115686214270977), 25 
(1582115686214270978), 29 (1582115686214270979), 10030 (1582115686214270980), 
33 (1582115686214270981), ... (13 adds)]} 0 8
   [junit4]   2> 390352 INFO  (qtp817567712-2677) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard2_replica_n2]  webapp=/solr 
path=/update params={_stateVer_=dv_coll:4&wt=javabin&version=2}{add=[0 
(1582115686204833792), 4 (1582115686213222400), 10005 (1582115686213222401), 12 
(1582115686213222402), 15 (1582115686214270976), 16 (1582115686214270977), 20 
(1582115686214270978), 22 (1582115686214270979), 34 (1582115686214270980), 35 
(1582115686214270981), ... (16 adds)]} 0 9
   [junit4]   2> 390356 INFO  (qtp1238004669-2670) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica_n1]  webapp=/solr 
path=/update params={_stateVer_=dv_coll:4&wt=javabin&version=2}{add=[1 
(1582115686207979520), 8 (1582115686217416704), 10 (1582115686217416705), 11 
(1582115686218465280), 13 (1582115686218465281), 14 (1582115686218465282), 
10015 (1582115686218465283), 10020 (1582115686218465284), 24 
(1582115686218465285), 27 (1582115686218465286), ... (16 adds)]} 0 11
   [junit4]   2> 390391 INFO  (Thread-486) [    ] o.a.s.u.DefaultSolrCoreState 
New IndexWriter is ready to be used.
   [junit4]   2> 390394 INFO  (qtp1238004669-2673) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica_n4]  webapp=/solr 
path=/update params={_stateVer_=dv_coll:4&wt=javabin&version=2}{add=[2 
(1582115686205882368), 3 (1582115686219513856), 6 (1582115686219513857), 7 
(1582115686258311168), 9 (1582115686259359744), 10010 (1582115686259359745), 18 
(1582115686259359746), 10025 (1582115686259359747), 26 (1582115686259359748), 
30 (1582115686259359749), ... (14 adds)]} 0 51
   [junit4]   2> 390400 INFO  (qtp817567712-2663) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1582115686265651200,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 390400 INFO  (qtp817567712-2676) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1582115686265651200,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 390400 INFO  (qtp817567712-2676) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@11f28421 
commitCommandVersion:1582115686265651200
   [junit4]   2> 390400 INFO  (qtp1238004669-2672) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1582115686265651200,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 390401 INFO  (qtp817567712-2663) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4d363403 
commitCommandVersion:1582115686265651200
   [junit4]   2> 390401 INFO  (qtp1238004669-2672) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@51a82e58 
commitCommandVersion:1582115686265651200
   [junit4]   2> 390401 INFO  (qtp1238004669-2669) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1582115686266699776,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 390402 INFO  (Thread-486) [    ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@399ec603[dv_coll_shard3_replica_n4] main]
   [junit4]   2> 390404 INFO  (searcherExecutor-986-thread-1) [    ] 
o.a.s.c.SolrCore [dv_coll_shard3_replica_n4] Registered new searcher 
Searcher@399ec603[dv_coll_shard3_replica_n4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):C2)))}
   [junit4]   2> 390405 INFO  (qtp1238004669-2669) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@b57df9 
commitCommandVersion:1582115686266699776
   [junit4]   2> 390429 INFO  (qtp817567712-2676) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@180f0161[dv_coll_shard4_replica_n6] 
main]
   [junit4]   2> 390430 INFO  (qtp817567712-2676) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 390431 INFO  (searcherExecutor-958-thread-1) [    ] 
o.a.s.c.SolrCore [dv_coll_shard4_replica_n6] Registered new searcher 
Searcher@180f0161[dv_coll_shard4_replica_n6] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):C13)))}
   [junit4]   2> 390431 INFO  (qtp817567712-2676) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard4_replica_n6]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:50524/solr/dv_coll_shard3_replica_n4/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 32
   [junit4]   2> 390439 INFO  (qtp817567712-2663) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3beef977[dv_coll_shard2_replica_n2] 
main]
   [junit4]   2> 390440 INFO  (qtp817567712-2663) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 390442 INFO  (searcherExecutor-960-thread-1) [    ] 
o.a.s.c.SolrCore [dv_coll_shard2_replica_n2] Registered new searcher 
Searcher@3beef977[dv_coll_shard2_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):C16)))}
   [junit4]   2> 390442 INFO  (qtp817567712-2663) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard2_replica_n2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:50524/solr/dv_coll_shard3_replica_n4/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 43
   [junit4]   2> 390450 INFO  (qtp1238004669-2669) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@68274f6[dv_coll_shard1_replica_n1] 
main]
   [junit4]   2> 390451 INFO  (qtp1238004669-2669) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 390452 INFO  (searcherExecutor-978-thread-1) [    ] 
o.a.s.c.SolrCore [dv_coll_shard1_replica_n1] Registered new searcher 
Searcher@68274f6[dv_coll_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):C16)))}
   [junit4]   2> 390452 INFO  (qtp1238004669-2669) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica_n1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:50524/solr/dv_coll_shard3_replica_n4/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 51
   [junit4]   2> 390453 INFO  (qtp1238004669-2672) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1be06ac0[dv_coll_shard3_replica_n4] 
main]
   [junit4]   2> 390453 INFO  (qtp1238004669-2672) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 390454 INFO  (searcherExecutor-977-thread-1) [    ] 
o.a.s.c.SolrCore [dv_coll_shard3_replica_n4] Registered new searcher 
Searcher@1be06ac0[dv_coll_shard3_replica_n4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):C2)
 Uninverting(_1(7.2.0):C12)))}
   [junit4]   2> 390454 INFO  (qtp1238004669-2672) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica_n4]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:50524/solr/dv_coll_shard3_replica_n4/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 54
   [junit4]   2> 390456 INFO  (qtp1238004669-2667) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica_n4]  webapp=/solr 
path=/update 
params={_stateVer_=dv_coll:4&commit=true&wt=javabin&version=2}{commit=} 0 58
   [junit4]   2> 390456 INFO  (qtp1238004669-2667) [    ] o.a.s.c.SolrCore 
[dv_coll_shard3_replica_n4]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@5be66667
   [junit4]   2> 390456 INFO  (qtp1238004669-2667) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.dv_coll.shard3.replica_n4, tag=1541826151
   [junit4]   2> 390456 INFO  (qtp1238004669-2667) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@559c4fd1: rootName = 
solr_50524, domain = solr.core.dv_coll.shard3.replica_n4, service url = null, 
agent id = null] for registry solr.core.dv_coll.shard3.replica_n4 / 
com.codahale.metrics.MetricRegistry@7eac0390
   [junit4]   2> 390462 INFO  (qtp1238004669-2667) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection.dv_coll.shard3.leader, tag=1541826151
   [junit4]   2> 390486 INFO  (qtp1238004669-2670) [    ] o.a.s.c.S.Request 
[dv_coll_shard1_replica_n1]  webapp=/solr path=/select 
params={df=text&group.distributed.first=true&distrib=false&_stateVer_=dv_coll:4&group.limit=100&fl=id,score&shards.purpose=2048&start=0&shard.url=https://127.0.0.1:50524/solr/dv_coll_shard1_replica_n1/&rows=100&version=2&q=*:*&NOW=1508823095638&isShard=true&wt=javabin&group.field=intGSF&group=true}
 status=0 QTime=17
   [junit4]   2> 390486 INFO  (qtp817567712-2665) [    ] o.a.s.c.S.Request 
[dv_coll_shard4_replica_n6]  webapp=/solr path=/select 
params={df=text&group.distributed.first=true&distrib=false&_stateVer_=dv_coll:4&group.limit=100&fl=id,score&shards.purpose=2048&start=0&shard.url=https://127.0.0.1:50525/solr/dv_coll_shard4_replica_n6/&rows=100&version=2&q=*:*&NOW=1508823095638&isShard=true&wt=javabin&group.field=intGSF&group=true}
 status=0 QTime=17
   [junit4]   2> 390486 INFO  (qtp1238004669-2674) [    ] o.a.s.c.S.Request 
[dv_coll_shard3_replica_n4]  webapp=/solr path=/select 
params={df=text&group.distributed.first=true&distrib=false&_stateVer_=dv_coll:4&group.limit=100&fl=id,score&shards.purpose=2048&start=0&shard.url=https://127.0.0.1:50524/solr/dv_coll_shard3_replica_n4/&rows=100&version=2&q=*:*&NOW=1508823095638&isShard=true&wt=javabin&group.field=intGSF&group=true}
 status=0 QTime=17
   [junit4]   2> 390486 INFO  (qtp817567712-2678) [    ] o.a.s.c.S.Request 
[dv_coll_shard2_replica_n2]  webapp=/solr path=/select 
params={df=text&group.distributed.first=true&distrib=false&_stateVer_=dv_coll:4&group.limit=100&fl=id,score&shards.purpose=2048&start=0&shard.url=https://127.0.0.1:50525/solr/dv_coll_shard2_replica_n2/&rows=100&version=2&q=*:*&NOW=1508823095638&isShard=true&wt=javabin&group.field=intGSF&group=true}
 status=0 QTime=17
   [junit4]   2> 390508 INFO  (qtp1238004669-2673) [    ] o.a.s.c.S.Request 
[dv_coll_shard3_replica_n4]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=dv_coll:4&group.limit=100&fl=id,score&shards.purpose=4&start=0&shard.url=https://127.0.0.1:50524/solr/dv_coll_shard3_replica_n4/&rows=100&version=2&group.distributed.second=true&q=*:*&NOW=1508823095638&group.topgroups.intGSF=318472789&group.topgroups.intGSF=318482797&group.topgroups.intGSF=318492803&group.topgroups.intGSF=%01&group.topgroups.intGSF=318502804&group.topgroups.intGSF=318512811&group.topgroups.intGSF=318522811&group.topgroups.intGSF=318532811&isShard=true&wt=javabin&group.field=intGSF&group=true}
 status=0 QTime=14
   [junit4]   2> 390508 INFO  (qtp817567712-2666) [    ] o.a.s.c.S.Request 
[dv_coll_shard4_replica_n6]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=dv_coll:4&group.limit=100&fl=id,score&shards.purpose=4&start=0&shard.url=https://127.0.0.1:50525/solr/dv_coll_shard4_replica_n6/&rows=100&version=2&group.distributed.second=true&q=*:*&NOW=1508823095638&group.topgroups.intGSF=318472789&group.topgroups.intGSF=318482797&group.topgroups.intGSF=318492803&group.topgroups.intGSF=%01&group.topgroups.intGSF=318502804&group.topgroups.intGSF=318512811&group.topgroups.intGSF=318522811&group.topgroups.intGSF=318532811&isShard=true&wt=javabin&group.field=intGSF&group=true}
 status=0 QTime=14
   [junit4]   2> 390508 INFO  (qtp817567712-2679) [    ] o.a.s.c.S.Request 
[dv_coll_shard2_replica_n2]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=dv_coll:4&group.limit=100&fl=id,score&shards.purpose=4&start=0&shard.url=https://127.0.0.1:50525/solr/dv_coll_shard2_replica_n2/&rows=100&version=2&group.distributed.second=true&q=*:*&NOW=1508823095638&group.topgroups.intGSF=318472789&group.topgroups.intGSF=318482797&group.topgroups.intGSF=318492803&group.topgroups.intGSF=%01&group.topgroups.intGSF=318502804&group.topgroups.intGSF=318512811&group.topgroups.intGSF=318522811&group.topgroups.intGSF=318532811&isShard=true&wt=javabin&group.field=intGSF&group=true}
 status=0 QTime=14
   [junit4]   2> 390509 INFO  (qtp1238004669-2669) [    ] o.a.s.c.S.Request 
[dv_coll_shard1_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=dv_coll:4&group.limit=100&fl=id,score&shards.purpose=4&start=0&shard.url=https://127.0.0.1:50524/solr/dv_coll_shard1_replica_n1/&rows=100&version=2&group.distributed.second=true&q=*:*&NOW=1508823095638&group.topgroups.intGSF=318472789&group.topgroups.intGSF=318482797&group.topgroups.intGSF=318492803&group.topgroups.intGSF=%01&group.topgroups.intGSF=318502804&group.topgroups.intGSF=318512811&group.topgroups.intGSF=318522811&group.topgroups.intGSF=318532811&isShard=true&wt=javabin&group.field=intGSF&group=true}
 status=0 QTime=14
   [junit4]   2> 390515 INFO  (qtp1238004669-2672) [    ] o.a.s.c.S.Request 
[dv_coll_shard3_replica_n4]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=dv_coll:4&group.limit=100&shards.purpose=64&shard.url=https://127.0.0.1:50524/solr/dv_coll_shard3_replica_n4/&rows=100&version=2&q=*:*&NOW=1508823095638&ids=2,3&isShard=true&wt=javabin&group.field=intGSF}
 status=0 QTime=0
   [junit4]   2> 390515 INFO  (qtp1238004669-2667) [    ] o.a.s.c.S.Request 
[dv_coll_shard1_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=dv_coll:4&group.limit=100&shards.purpose=64&shard.url=https://127.0.0.1:50524/solr/dv_coll_shard1_replica_n1/&rows=100&version=2&q=*:*&NOW=1508823095638&ids=11,13,24,14,48,27,38,10020,28,1,8,10015,40,42,10,32&isShard=true&wt=javabin&group.field=intGSF}
 status=0 QTime=0
   [junit4]   2> 390515 INFO  (qtp817567712-2676) [    ] o.a.s.c.S.Request 
[dv_coll_shard4_replica_n6]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=dv_coll:4&group.limit=100&shards.purpose=64&shard.url=https://127.0.0.1:50525/solr/dv_coll_shard4_replica_n6/&rows=100&version=2&q=*:*&NOW=1508823095638&ids=33,23,45,25,10040,37,17,10030,29,19,5,10000,21&isShard=true&wt=javabin&group.field=intGSF}
 status=0 QTime=0
   [junit4]   2> 390515 INFO  (qtp817567712-2677) [    ] o.a.s.c.S.Request 
[dv_coll_shard2_replica_n2]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=dv_coll:4&group.limit=100&shards.purpose=64&shard.url=https://127.0.0.1:50525/solr/dv_coll_shard2_replica_n2/&rows=100&version=2&q=*:*&NOW=1508823095638&ids=22,44,12,34,35,46,36,47,15,16,0,4,41,20,10005,43&isShard=true&wt=javabin&group.field=intGSF}
 status=0 QTime=0
   [junit4]   2> 390520 INFO  (qtp1238004669-2671) [    ] o.a.s.c.S.Request 
[dv_coll_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=dv_coll:4&group.limit=100&rows=100&wt=javabin&version=2&group.field=intGSF&group=true}
 status=0 QTime=57
   [junit4]   2> 390523 INFO  
(TEST-DocValuesNotIndexedTest.testGroupingDVOnly-seed#[1D207BC56E61C317]) [    
] o.a.s.SolrTestCaseJ4 ###Ending testGroupingDVOnly
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=DocValuesNotIndexedTest -Dtests.method=testGroupingDVOnly 
-Dtests.seed=1D207BC56E61C317 -Dtests.slow=true -Dtests.locale=fr-PM 
-Dtests.timezone=America/Antigua -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] FAILURE 0.31s J1 | DocValuesNotIndexedTest.testGroupingDVOnly <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Unexpected number of 
elements in the group for intGSF: 6
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([1D207BC56E61C317:869B159D2339F149]:0)
   [junit4]    >        at 
org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingDVOnly(DocValuesNotIndexedTest.java:377)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]    >        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]    >        at 
java.base/java.lang.reflect.Method.invoke(Method.java:564)
   [junit4]    >        at java.base/java.lang.Thread.run(Thread.java:844)
   [junit4]   2> 390537 INFO  
(TEST-DocValuesNotIndexedTest.testDistribFaceting-seed#[1D207BC56E61C317]) [    
] o.a.s.SolrTestCaseJ4 ###Starting testDistribFaceting
   [junit4]   2> 390543 INFO  (qtp817567712-2663) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@25dddc24[dv_coll_shard2_replica_n2] 
realtime]
   [junit4]   2> 390543 INFO  (qtp817567712-2678) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@2f9cf717[dv_coll_shard4_replica_n6] 
realtime]
   [junit4]   2> 390544 INFO  (qtp817567712-2678) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard4_replica_n6]  webapp=/solr 
path=/update 
params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:50525/solr/dv_coll_shard2_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1582115686414548992)} 0 1
   [junit4]   2> 390544 INFO  (qtp1238004669-2674) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7ffeffd4[dv_coll_shard3_replica_n4] 
realtime]
   [junit4]   2> 390547 INFO  (qtp1238004669-2669) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3e0c023d[dv_coll_shard1_replica_n1] 
realtime]
   [junit4]   2> 390547 INFO  (qtp1238004669-2669) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica_n1]  webapp=/solr 
path=/update 
params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:50525/solr/dv_coll_shard2_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1582115686415597568)} 0 3
   [junit4]   2> 390549 INFO  (qtp1238004669-2674) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5b65985e[dv_coll_shard3_replica_n4] 
realtime]
   [junit4]   2> 390551 INFO  (qtp1238004669-2674) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica_n4]  webapp=/solr 
path=/update 
params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:50525/solr/dv_coll_shard2_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1582115686415597568)} 0 8
   [junit4]   2> 390551 INFO  (qtp817567712-2663) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard2_replica_n2]  webapp=/solr 
path=/update params={wt=javabin&version=2}{deleteByQuery=*:* 
(-1582115686411403264)} 0 12
   [junit4]   2> 390555 INFO  (qtp1238004669-2667) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1582115686428180480,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 390555 INFO  (qtp817567712-2666) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1582115686428180480,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 390555 INFO  (qtp1238004669-2667) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@b57df9 
commitCommandVersion:1582115686428180480
   [junit4]   2> 390555 INFO  (qtp817567712-2665) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1582115686428180480,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 390555 INFO  (qtp817567712-2665) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4d363403 
commitCommandVersion:1582115686428180480
   [junit4]   2> 390556 INFO  (qtp817567712-2666) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@11f28421 
commitCommandVersion:1582115686428180480
   [junit4]   2> 390556 INFO  (qtp1238004669-2671) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1582115686429229056,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 390556 INFO  (qtp1238004669-2671) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@51a82e58 
commitCommandVersion:1582115686429229056
   [junit4]   2> 390557 INFO  (qtp1238004669-2667) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@679e08a0[dv_coll_shard1_replica_n1] 
main]
   [junit4]   2> 390557 INFO  (qtp1238004669-2667) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 390558 INFO  (qtp817567712-2665) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@606dd14e[dv_coll_shard2_replica_n2] 
main]
   [junit4]   2> 390559 INFO  (qtp817567712-2665) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 390559 INFO  (qtp817567712-2666) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@48c0ce35[dv_coll_shard4_replica_n6] 
main]
   [junit4]   2> 390559 INFO  (searcherExecutor-978-thread-1) [    ] 
o.a.s.c.SolrCore [dv_coll_shard1_replica_n1] Registered new searcher 
Searcher@679e08a0[dv_coll_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 390560 INFO  (qtp817567712-2666) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 390561 INFO  (searcherExecutor-960-thread-1) [    ] 
o.a.s.c.SolrCore [dv_coll_shard2_replica_n2] Registered new searcher 
Searcher@606dd14e[dv_coll_shard2_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 390561 INFO  (searcherExecutor-958-thread-1) [    ] 
o.a.s.c.SolrCore [dv_coll_shard4_replica_n6] Registered new searcher 
Searcher@48c0ce35[dv_coll_shard4_replica_n6] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 390562 INFO  (qtp1238004669-2667) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica_n1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.fro

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

eeb{/solr,null,UNAVAILABLE}
   [junit4]   2> 2536714 ERROR 
(SUITE-CustomCollectionTest-seed#[1D207BC56E61C317]-worker) [    ] 
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper 
server won't take any action on ERROR or SHUTDOWN server state changes
   [junit4]   2> 2536715 INFO  
(SUITE-CustomCollectionTest-seed#[1D207BC56E61C317]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:60255 60255
   [junit4]   2> 2536740 INFO  (Thread-4703) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:60255 60255
   [junit4]   2> 2536855 WARN  (Thread-4703) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        14      /solr/configs/conf
   [junit4]   2>        9       /solr/clusterprops.json
   [junit4]   2>        9       /solr/aliases.json
   [junit4]   2>        5       /solr/collections/withShardField/state.json
   [junit4]   2>        4       /solr/security.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        56      /solr/collections/implicitcoll/state.json
   [junit4]   2>        54      /solr/collections/withShardField/state.json
   [junit4]   2>        44      /solr/collections/routeFieldColl/state.json
   [junit4]   2>        25      
/solr/collections/testCreateShardRepFactor/state.json
   [junit4]   2>        9       /solr/clusterstate.json
   [junit4]   2>        2       
/solr/overseer_elect/election/98882366682562571-127.0.0.1:60258_solr-n_0000000000
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        27      /solr/collections
   [junit4]   2>        9       /solr/live_nodes
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_1D207BC56E61C317-001
   [junit4]   2> NOTE: test params are: codec=CheapBastard, 
sim=RandomSimilarity(queryNorm=false): {}, locale=en-GY, timezone=Asia/Kuching
   [junit4]   2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 9 
(64-bit)/cpus=3,threads=1,free=143754688,total=525860864
   [junit4]   2> NOTE: All tests run in this JVM: [BasicDistributedZk2Test, 
TestHalfAndHalfDocValues, TestAnalyzedSuggestions, TestUseDocValuesAsStored2, 
TestSizeLimitedDistributedMap, TestPrepRecovery, 
ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestCustomSort, 
TestHttpShardHandlerFactory, TestCloudPseudoReturnFields, TestSort, 
TestDistribIDF, TestConfigSetImmutable, TestMiniSolrCloudClusterSSL, 
MigrateRouteKeyTest, ExplicitHLLTest, TestSimpleTextCodec, 
SpellCheckCollatorWithCollapseTest, TestRTGBase, TestManagedStopFilterFactory, 
TestNumericRangeQuery64, ZkStateReaderTest, DocumentBuilderTest, 
TestSQLHandlerNonCloud, TestTrie, SolrGraphiteReporterTest, LargeFieldTest, 
TestPayloadCheckQParserPlugin, TestHdfsUpdateLog, SynonymTokenizerTest, 
DocValuesNotIndexedTest, CloudExitableDirectoryReaderTest, 
TestPostingsSolrHighlighter, TestScoreJoinQPScore, ReplicationFactorTest, 
OverseerStatusTest, TestNamedUpdateProcessors, TestSlowCompositeReaderWrapper, 
AutoAddReplicasPlanActionTest, ZkNodePropsTest, TestSearcherReuse, 
VersionInfoTest, TestReplicationHandlerBackup, AliasIntegrationTest, 
TestExtendedDismaxParser, TestManagedSynonymGraphFilterFactory, 
TestLMJelinekMercerSimilarityFactory, TestApiFramework, TestExportWriter, 
ClassificationUpdateProcessorTest, TestFastWriter, TestQueryWrapperFilter, 
DeleteStatusTest, TestRandomRequestDistribution, HttpPartitionTest, TestReload, 
SearchRateTriggerTest, RemoteQueryErrorTest, NodeAddedTriggerTest, 
TestLuceneMatchVersion, TestSystemIdResolver, SolrPluginUtilsTest, 
TestCrossCoreJoin, TestTrieFacet, DOMUtilTest, TestSchemaSimilarityResource, 
PropertiesRequestHandlerTest, SolrMetricsIntegrationTest, SOLR749Test, 
CdcrUpdateLogTest, TestCollationField, TestLegacyNumericRangeQueryBuilder, 
TermVectorComponentTest, JSONWriterTest, FullSolrCloudDistribCmdsTest, 
TestEmbeddedSolrServerConstructors, TestValueSourceCache, 
TestTolerantUpdateProcessorRandomCloud, TestHashQParserPlugin, 
TestSolrCoreSnapshots, TestFaceting, CoreAdminHandlerTest, FileUtilsTest, 
TestJettySolrRunner, TestZkAclsWithHadoopAuth, TestStressVersions, 
BlockCacheTest, TestConfigsApi, TestLuceneIndexBackCompat, 
TestXmlQParserPlugin, TestSchemaNameResource, BadComponentTest, 
TestElisionMultitermQuery, TestBM25SimilarityFactory, BufferStoreTest, 
ShufflingReplicaListTransformerTest, DistributedIntervalFacetingTest, 
FileBasedSpellCheckerTest, AnalyticsQueryTest, TestDeleteCollectionOnDownNodes, 
TestInitParams, BaseCdcrDistributedZkTest, TestCloudSchemaless, 
TestCharFilters, SpellingQueryConverterTest, DistributedQueueTest, 
TestCloudDeleteByQuery, HdfsChaosMonkeyNothingIsSafeTest, GraphQueryTest, 
TestSchemalessBufferedUpdates, SolrCloudReportersTest, TestLegacyFieldCache, 
ParsingFieldUpdateProcessorsTest, ActionThrottleTest, 
TestFieldCacheVsDocValues, TestCustomStream, PolyFieldTest, 
TestFreeTextSuggestions, TestStressInPlaceUpdates, 
DistributedQueryComponentCustomSortTest, 
SuggestComponentContextFilterQueryTest, SolrMetricManagerTest, 
SimplePostToolTest, ClassificationUpdateProcessorFactoryTest, 
TestSubQueryTransformer, ConnectionManagerTest, CollectionStateFormat2Test, 
MoveReplicaTest, TestSolrQueryResponse, MoveReplicaHDFSFailoverTest, 
TestPartialUpdateDeduplication, TestMultiValuedNumericRangeQuery, 
AddBlockUpdateTest, CdcrRequestHandlerTest, HdfsRestartWhileUpdatingTest, 
TestRemoteStreaming, SparseHLLTest, MBeansHandlerTest, 
TestDynamicFieldCollectionResource, TestLRUCache, TestTlogReplica, 
DeleteLastCustomShardedReplicaTest, ComputePlanActionTest, 
CollectionsAPIDistributedZkTest, TestBulkSchemaConcurrent, TestSolrXml, 
ManagedSchemaRoundRobinCloudTest, TestPointFields, TestImplicitCoreProperties, 
TestSegmentSorting, TestCloudJSONFacetJoinDomain, 
TestDistributedStatsComponentCardinality, SolrCoreCheckLockOnStartupTest, 
StatelessScriptUpdateProcessorFactoryTest, TestCoreContainer, 
TestNonDefinedSimilarityFactory, TestFieldTypeCollectionResource, 
TestJsonFacets, TestTrackingShardHandlerFactory, DocValuesMissingTest, 
PKIAuthenticationIntegrationTest, TestSolrQueryParser, 
TestDocBasedVersionConstraints, TestFastLRUCache, TestLegacyField, 
HdfsDirectoryFactoryTest, HdfsAutoAddReplicasIntegrationTest, 
OverriddenZkACLAndCredentialsProvidersTest, RankQueryTest, TestPolicyCloud, 
TestSimpleTrackingShardHandler, TestInPlaceUpdatesStandalone, ZkFailoverTest, 
TestFilteredDocIdSet, TestGraphMLResponseWriter, DistributedDebugComponentTest, 
TriLevelCompositeIdRoutingTest, TestManagedSchemaAPI, TestFieldCacheSort, 
AtomicUpdateProcessorFactoryTest, SubstringBytesRefFilterTest, 
SoftAutoCommitTest, ChaosMonkeySafeLeaderTest, OpenCloseCoreStressTest, 
LeaderElectionIntegrationTest, ShardRoutingTest, BasicZkTest, 
TestReplicationHandler, TestRandomFaceting, ZkSolrClientTest, TestZkChroot, 
ZkCLITest, ShardRoutingCustomTest, TestDistributedSearch, 
TestDistributedGrouping, TestHashPartitioner, 
DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, 
TestStressReorder, TestJoin, TestRangeQuery, SimpleFacetsTest, SolrCoreTest, 
TestGroupingSearch, SolrCmdDistributorTest, QueryElevationComponentTest, 
PeerSyncTest, BadIndexSchemaTest, ConvertedLegacyTest, TestFunctionQuery, 
BasicFunctionalityTest, TestLazyCores, DirectUpdateHandlerTest, TestBadConfig, 
TestIndexSearcher, HighlighterTest, ShowFileRequestHandlerTest, 
DistributedQueryElevationComponentTest, SolrIndexSplitterTest, 
TestCoreDiscovery, AnalysisAfterCoreReloadTest, SpellCheckCollatorTest, 
SuggesterFSTTest, SolrRequestParserTest, TestFoldingMultitermQuery, 
DocValuesTest, TestStressLucene, SuggesterWFSTTest, TestCSVLoader, 
NoCacheHeaderTest, WordBreakSolrSpellCheckerTest, 
SchemaVersionSpecificBehaviorTest, TestPseudoReturnFields, 
FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, 
TestWordDelimiterFilterFactory, QueryEqualityTest, 
DefaultValueUpdateProcessorTest, SortByFunctionTest, DocValuesMultiTest, 
TestSolrDeletionPolicy1, XsltUpdateRequestHandlerTest, DebugComponentTest, 
CacheHeaderTest, TestSurroundQueryParser, LukeRequestHandlerTest, 
TestQueryUtils, DirectSolrSpellCheckerTest, TestReversedWildcardFilterFactory, 
DocumentAnalysisRequestHandlerTest, TestQueryTypes, PrimitiveFieldTypeTest, 
TermsComponentTest, XmlUpdateRequestHandlerTest, RequestHandlersTest, 
MoreLikeThisHandlerTest, FieldAnalysisRequestHandlerTest, 
IndexSchemaRuntimeFieldTest, LoggingHandlerTest, RegexBoostProcessorTest, 
IndexSchemaTest, ReturnFieldsTest, UpdateRequestProcessorFactoryTest, 
TestCSVResponseWriter, QueryParsingTest, JsonLoaderTest, 
BinaryUpdateRequestHandlerTest, TestPHPSerializedResponseWriter, 
PingRequestHandlerTest, CSVRequestHandlerTest, TestComponentsName, 
SearchHandlerTest, UpdateParamsTest, AlternateDirectoryTest, 
TestSolrIndexConfig, TestQuerySenderNoQuery, CopyFieldTest, 
ResponseLogComponentTest, SolrIndexConfigTest, TestStressRecovery, 
TestMergePolicyConfig, SampleTest, TestDocSet, TestSearchPerf, 
MinimalSchemaTest, TestConfig, OutputWriterTest, ExternalFileFieldSortTest, 
DirectSolrConnectionTest, NotRequiredUniqueKeyTest, 
SpellPossibilityIteratorTest, TestCodecSupport, TestXIncludeConfig, 
EchoParamsTest, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, 
TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, ResourceLoaderTest, 
TestFastOutputStream, OpenExchangeRatesOrgProviderTest, 
URLClassifyProcessorTest, PluginInfoTest, PreAnalyzedFieldTest, PrimUtilsTest, 
TestSuggestSpellingConverter, DateFieldTest, ClusterStateTest, TestSolrJ, 
TestUtils, TestDocumentBuilder, SliceStateTest, SystemInfoHandlerTest, 
DistributedMLTComponentTest, CursorPagingTest, SolrTestCaseJ4Test, 
TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort, 
TestHighlightDedupGrouping, AssignTest, CollectionTooManyReplicasTest, 
ConcurrentDeleteAndCreateCollectionTest, ConfigSetsAPITest, 
CustomCollectionTest]
   [junit4] Completed [531/744 (2!)] on J1 in 66.85s, 4 tests, 1 error <<< 
FAILURES!

[...truncated 40886 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to