You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/03/24 17:38:14 UTC

[JENKINS] Lucene-Solr-master-Windows (64bit/jdk-9.0.4) - Build # 7828 - Unstable!

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7828/
Java: 64bit/jdk-9.0.4 -XX:-UseCompressedOops -XX:+UseG1GC

5 tests failed.
FAILED:  org.apache.solr.handler.TestSQLHandler.doTest

Error Message:
--> http://127.0.0.1:60435/khlo/fy/collection1_shard2_replica_n1:Failed to execute sqlQuery 'select id, field_i, str_s, field_i_p, field_f_p, field_d_p, field_l_p from collection1 where (text='(XXXX)' OR text='XXXX') AND text='XXXX' order by field_i desc' against JDBC connection 'jdbc:calcitesolr:'. Error while executing SQL "select id, field_i, str_s, field_i_p, field_f_p, field_d_p, field_l_p from collection1 where (text='(XXXX)' OR text='XXXX') AND text='XXXX' order by field_i desc": java.io.IOException: java.util.concurrent.ExecutionException: java.io.IOException: --> http://127.0.0.1:60435/khlo/fy/collection1_shard2_replica_n1/:can not sort on a field w/o docValues unless it is indexed=true uninvertible=true and the type supports Uninversion: field_i

Stack Trace:
java.io.IOException: --> http://127.0.0.1:60435/khlo/fy/collection1_shard2_replica_n1:Failed to execute sqlQuery 'select id, field_i, str_s, field_i_p, field_f_p, field_d_p, field_l_p from collection1 where (text='(XXXX)' OR text='XXXX') AND text='XXXX' order by field_i desc' against JDBC connection 'jdbc:calcitesolr:'.
Error while executing SQL "select id, field_i, str_s, field_i_p, field_f_p, field_d_p, field_l_p from collection1 where (text='(XXXX)' OR text='XXXX') AND text='XXXX' order by field_i desc": java.io.IOException: java.util.concurrent.ExecutionException: java.io.IOException: --> http://127.0.0.1:60435/khlo/fy/collection1_shard2_replica_n1/:can not sort on a field w/o docValues unless it is indexed=true uninvertible=true and the type supports Uninversion: field_i
	at __randomizedtesting.SeedInfo.seed([2ECAF1B27020E0BB:898E49161D9BF302]:0)
	at org.apache.solr.client.solrj.io.stream.SolrStream.read(SolrStream.java:215)
	at org.apache.solr.handler.TestSQLHandler.getTuples(TestSQLHandler.java:2517)
	at org.apache.solr.handler.TestSQLHandler.testBasicSelect(TestSQLHandler.java:148)
	at org.apache.solr.handler.TestSQLHandler.doTest(TestSQLHandler.java:99)
	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:1750)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at java.base/java.lang.Thread.run(Thread.java:844)


FAILED:  org.apache.solr.handler.TestSQLHandler.doTest

Error Message:
--> http://127.0.0.1:61307/khlo/fy/collection1_shard2_replica_n6:Failed to execute sqlQuery 'select id, field_i, str_s, field_i_p, field_f_p, field_d_p, field_l_p from collection1 where (text='(XXXX)' OR text='XXXX') AND text='XXXX' order by field_i desc' against JDBC connection 'jdbc:calcitesolr:'. Error while executing SQL "select id, field_i, str_s, field_i_p, field_f_p, field_d_p, field_l_p from collection1 where (text='(XXXX)' OR text='XXXX') AND text='XXXX' order by field_i desc": java.io.IOException: java.util.concurrent.ExecutionException: java.io.IOException: --> http://127.0.0.1:61307/khlo/fy/collection1_shard2_replica_n6/:can not sort on a field w/o docValues unless it is indexed=true uninvertible=true and the type supports Uninversion: field_i

Stack Trace:
java.io.IOException: --> http://127.0.0.1:61307/khlo/fy/collection1_shard2_replica_n6:Failed to execute sqlQuery 'select id, field_i, str_s, field_i_p, field_f_p, field_d_p, field_l_p from collection1 where (text='(XXXX)' OR text='XXXX') AND text='XXXX' order by field_i desc' against JDBC connection 'jdbc:calcitesolr:'.
Error while executing SQL "select id, field_i, str_s, field_i_p, field_f_p, field_d_p, field_l_p from collection1 where (text='(XXXX)' OR text='XXXX') AND text='XXXX' order by field_i desc": java.io.IOException: java.util.concurrent.ExecutionException: java.io.IOException: --> http://127.0.0.1:61307/khlo/fy/collection1_shard2_replica_n6/:can not sort on a field w/o docValues unless it is indexed=true uninvertible=true and the type supports Uninversion: field_i
	at __randomizedtesting.SeedInfo.seed([2ECAF1B27020E0BB:898E49161D9BF302]:0)
	at org.apache.solr.client.solrj.io.stream.SolrStream.read(SolrStream.java:215)
	at org.apache.solr.handler.TestSQLHandler.getTuples(TestSQLHandler.java:2517)
	at org.apache.solr.handler.TestSQLHandler.testBasicSelect(TestSQLHandler.java:148)
	at org.apache.solr.handler.TestSQLHandler.doTest(TestSQLHandler.java:99)
	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:1750)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at java.base/java.lang.Thread.run(Thread.java:844)


FAILED:  org.apache.solr.handler.TestSQLHandler.doTest

Error Message:
--> http://127.0.0.1:61397/khlo/fy/collection1_shard2_replica_n2:Failed to execute sqlQuery 'select id, field_i, str_s, field_i_p, field_f_p, field_d_p, field_l_p from collection1 where (text='(XXXX)' OR text='XXXX') AND text='XXXX' order by field_i desc' against JDBC connection 'jdbc:calcitesolr:'. Error while executing SQL "select id, field_i, str_s, field_i_p, field_f_p, field_d_p, field_l_p from collection1 where (text='(XXXX)' OR text='XXXX') AND text='XXXX' order by field_i desc": java.io.IOException: java.util.concurrent.ExecutionException: java.io.IOException: --> http://127.0.0.1:61397/khlo/fy/collection1_shard2_replica_n2/:can not sort on a field w/o docValues unless it is indexed=true uninvertible=true and the type supports Uninversion: field_i

Stack Trace:
java.io.IOException: --> http://127.0.0.1:61397/khlo/fy/collection1_shard2_replica_n2:Failed to execute sqlQuery 'select id, field_i, str_s, field_i_p, field_f_p, field_d_p, field_l_p from collection1 where (text='(XXXX)' OR text='XXXX') AND text='XXXX' order by field_i desc' against JDBC connection 'jdbc:calcitesolr:'.
Error while executing SQL "select id, field_i, str_s, field_i_p, field_f_p, field_d_p, field_l_p from collection1 where (text='(XXXX)' OR text='XXXX') AND text='XXXX' order by field_i desc": java.io.IOException: java.util.concurrent.ExecutionException: java.io.IOException: --> http://127.0.0.1:61397/khlo/fy/collection1_shard2_replica_n2/:can not sort on a field w/o docValues unless it is indexed=true uninvertible=true and the type supports Uninversion: field_i
	at __randomizedtesting.SeedInfo.seed([2ECAF1B27020E0BB:898E49161D9BF302]:0)
	at org.apache.solr.client.solrj.io.stream.SolrStream.read(SolrStream.java:215)
	at org.apache.solr.handler.TestSQLHandler.getTuples(TestSQLHandler.java:2517)
	at org.apache.solr.handler.TestSQLHandler.testBasicSelect(TestSQLHandler.java:148)
	at org.apache.solr.handler.TestSQLHandler.doTest(TestSQLHandler.java:99)
	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:1750)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at java.base/java.lang.Thread.run(Thread.java:844)


FAILED:  org.apache.solr.handler.TestSQLHandler.doTest

Error Message:


Stack Trace:
java.lang.AssertionError
	at __randomizedtesting.SeedInfo.seed([2ECAF1B27020E0BB:898E49161D9BF302]:0)
	at org.apache.solr.handler.TestSQLHandler.testBasicSelect(TestSQLHandler.java:238)
	at org.apache.solr.handler.TestSQLHandler.doTest(TestSQLHandler.java:99)
	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:1750)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at java.base/java.lang.Thread.run(Thread.java:844)


FAILED:  junit.framework.TestSuite.org.apache.solr.schema.TestUseDocValuesAsStored

Error Message:
Could not remove the following files (in the order of attempts):    C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\init-core-data-001    C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\init-core-data-001\snapshot_metadata: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\init-core-data-001\snapshot_metadata    C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001 

Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
   C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\init-core-data-001
   C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\init-core-data-001\snapshot_metadata: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\init-core-data-001\snapshot_metadata
   C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001

	at __randomizedtesting.SeedInfo.seed([2ECAF1B27020E0BB]:0)
	at org.apache.lucene.util.IOUtils.rm(IOUtils.java:318)
	at org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216)
	at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at java.base/java.lang.Thread.run(Thread.java:844)




Build Log:
[...truncated 12657 lines...]
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4]   2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\init-core-data-001
   [junit4]   2> 57548 INFO  (SUITE-TestUseDocValuesAsStored-seed#[2ECAF1B27020E0BB]-worker) [    ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 57548 WARN  (SUITE-TestUseDocValuesAsStored-seed#[2ECAF1B27020E0BB]-worker) [    ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=8 numCloses=8
   [junit4]   2> 57548 INFO  (SUITE-TestUseDocValuesAsStored-seed#[2ECAF1B27020E0BB]-worker) [    ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 57548 INFO  (SUITE-TestUseDocValuesAsStored-seed#[2ECAF1B27020E0BB]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
   [junit4]   2> 57561 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testOnEmptyIndex
   [junit4]   2> 57600 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 57602 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.SolrResourceLoader Using system property solr.solr.home: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\tempDir-001
   [junit4]   2> 57624 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 57662 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema-non-stored-docvalues.xml instead
   [junit4]   2> 57673 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 57702 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
   [junit4]   2> 57719 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\tempDir-001\collection1\conf\managed-schema
   [junit4]   2> 57733 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\tempDir-001\collection1\conf\schema-non-stored-docvalues.xml to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\tempDir-001\collection1\conf\schema-non-stored-docvalues.xml.bak
   [junit4]   2> 58367 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 58406 WARN  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@19f0f786[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 58417 WARN  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@3a988dab[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 58576 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 58576 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 58647 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@20616d38
   [junit4]   2> 58657 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@20616d38
   [junit4]   2> 58657 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@20616d38
   [junit4]   2> 58683 INFO  (coreLoadExecutor-74-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 58745 INFO  (coreLoadExecutor-74-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
   [junit4]   2> 58808 INFO  (coreLoadExecutor-74-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
   [junit4]   2> 58808 INFO  (coreLoadExecutor-74-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\tempDir-001\collection1, trusted=true
   [junit4]   2> 58809 INFO  (coreLoadExecutor-74-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@20616d38
   [junit4]   2> 58810 INFO  (coreLoadExecutor-74-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\tempDir-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\init-core-data-001\]
   [junit4]   2> 58849 INFO  (coreLoadExecutor-74-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=85.80859375, floorSegmentMB=2.1572265625, forceMergeDeletesPctAllowed=11.277803191032081, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.39953225376907553, deletesPctAllowed=37.10098958963946
   [junit4]   2> 58872 WARN  (coreLoadExecutor-74-thread-1) [    x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, registerPath=/solr,/v2, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 59086 INFO  (coreLoadExecutor-74-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 59086 INFO  (coreLoadExecutor-74-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 59086 INFO  (coreLoadExecutor-74-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=51.390625, floorSegmentMB=1.2900390625, forceMergeDeletesPctAllowed=0.7690438877460426, segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4155291702853404, deletesPctAllowed=29.556819373250722
   [junit4]   2> 59346 INFO  (coreLoadExecutor-74-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5595ac9f[collection1] main]
   [junit4]   2> 59347 INFO  (coreLoadExecutor-74-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\tempDir-001\collection1\conf
   [junit4]   2> 59348 INFO  (coreLoadExecutor-74-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 59348 INFO  (searcherExecutor-75-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5595ac9f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 59361 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 59367 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 59367 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*} 0 7
   [junit4]   2> 59508 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 59508 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@446315ab commitCommandVersion:0
   [junit4]   2> 59533 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2ab2b8f7[collection1] main]
   [junit4]   2> 59535 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 59536 INFO  (searcherExecutor-75-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2ab2b8f7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 59551 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 173
   [junit4]   2> 59553 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=2
   [junit4]   2> 59570 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 59570 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 59570 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*,test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 59581 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[xyz]} 0 9
   [junit4]   2> 59584 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=1
   [junit4]   2> 59584 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 59585 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 59585 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*,test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 59585 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 59585 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@446315ab commitCommandVersion:0
   [junit4]   2> 59656 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@129435d1[collection1] main]
   [junit4]   2> 59660 INFO  (searcherExecutor-75-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@129435d1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442412423}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 59661 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 59661 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 75
   [junit4]   2> 59662 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 59668 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 59668 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=test_nonstored_dv_str&wt=xml} hits=1 status=0 QTime=1
   [junit4]   2> 59674 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*,test_nonstored_dv_str&wt=xml} hits=1 status=0 QTime=1
   [junit4]   2> 59676 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[xyz]} 0 0
   [junit4]   2> 59681 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 59681 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@446315ab commitCommandVersion:0
   [junit4]   2> 59715 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@18aeaf89[collection1] main]
   [junit4]   2> 59721 INFO  (searcherExecutor-75-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@18aeaf89[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442412512}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 59722 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 59739 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 58
   [junit4]   2> 59740 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 59741 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 59741 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*} 0 0
   [junit4]   2> 59741 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 59741 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@446315ab commitCommandVersion:0
   [junit4]   2> 59767 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@447fd4fe[collection1] main]
   [junit4]   2> 59767 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 59769 INFO  (searcherExecutor-75-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@447fd4fe[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 59808 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 67
   [junit4]   2> 59809 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 59809 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1643547644
   [junit4]   2> 59912 INFO  (coreCloseExecutor-79-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@69888135
   [junit4]   2> 59912 INFO  (coreCloseExecutor-79-thread-1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=69888135
   [junit4]   2> 59912 INFO  (coreCloseExecutor-79-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1d6c5be4: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@4a5b75de
   [junit4]   2> 59937 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 60036 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7432011: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@35dae40a
   [junit4]   2> 60053 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 60053 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6e15024b: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@6a7ba8c6
   [junit4]   2> 60066 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 60066 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@e00977a: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2ca8448c
   [junit4]   2> 60342 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testOnEmptyIndex
   [junit4]   2> 60350 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued
   [junit4]   2> 60386 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 60432 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 60471 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema-non-stored-docvalues.xml instead
   [junit4]   2> 60493 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 60522 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
   [junit4]   2> 60531 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\tempDir-002\collection1\conf\managed-schema
   [junit4]   2> 60535 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\tempDir-002\collection1\conf\schema-non-stored-docvalues.xml to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\tempDir-002\collection1\conf\schema-non-stored-docvalues.xml.bak
   [junit4]   2> 62510 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 62521 WARN  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7ca36ce1[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 62553 WARN  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@651e93e2[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 62600 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 62600 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 62707 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@20616d38
   [junit4]   2> 62718 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@20616d38
   [junit4]   2> 62718 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@20616d38
   [junit4]   2> 62749 INFO  (coreLoadExecutor-89-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 62763 INFO  (coreLoadExecutor-89-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
   [junit4]   2> 62783 INFO  (coreLoadExecutor-89-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
   [junit4]   2> 62783 INFO  (coreLoadExecutor-89-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\tempDir-002\collection1, trusted=true
   [junit4]   2> 62783 INFO  (coreLoadExecutor-89-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@20616d38
   [junit4]   2> 62783 INFO  (coreLoadExecutor-89-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\tempDir-002\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\init-core-data-001\]
   [junit4]   2> 62792 WARN  (coreLoadExecutor-89-thread-1) [    x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, registerPath=/solr,/v2, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 62935 INFO  (coreLoadExecutor-89-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 62935 INFO  (coreLoadExecutor-89-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 62941 INFO  (coreLoadExecutor-89-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=85.80859375, floorSegmentMB=2.1572265625, forceMergeDeletesPctAllowed=11.277803191032081, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.39953225376907553, deletesPctAllowed=37.10098958963946
   [junit4]   2> 63089 INFO  (coreLoadExecutor-89-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@47a6969f[collection1] main]
   [junit4]   2> 63126 INFO  (coreLoadExecutor-89-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_2ECAF1B27020E0BB-001\tempDir-002\collection1\conf
   [junit4]   2> 63128 INFO  (coreLoadExecutor-89-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 63128 INFO  (searcherExecutor-90-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@47a6969f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 63131 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 63132 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 63133 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*} 0 1
   [junit4]   2> 63142 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 6
   [junit4]   2> 63143 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63143 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1c89bf6 commitCommandVersion:0
   [junit4]   2> 63228 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@114787df[collection1] main]
   [junit4]   2> 63229 INFO  (searcherExecutor-90-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@114787df[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442415979}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 63229 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 63229 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 87
   [junit4]   2> 63229 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:1&fl=test_ss_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63253 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:1&fl=test_ss_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63263 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:1&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63263 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:1&fl=*&wt=xml} hits=1 status=0 QTime=1
   [junit4]   2> 63273 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:1&fl=test_ss_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=1
   [junit4]   2> 63283 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63293 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 1
   [junit4]   2> 63293 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63293 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1c89bf6 commitCommandVersion:0
   [junit4]   2> 63327 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2665f1c[collection1] main]
   [junit4]   2> 63329 INFO  (searcherExecutor-90-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2665f1c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442415979}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416132}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 63329 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 63331 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 36
   [junit4]   2> 63333 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:2&fl=test_i_dvo&wt=xml} hits=1 status=0 QTime=2
   [junit4]   2> 63336 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:2&fl=test_i_dvo,*&wt=xml} hits=1 status=0 QTime=1
   [junit4]   2> 63336 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:2&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63336 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:2&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63349 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:2&fl=test_i_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=1
   [junit4]   2> 63351 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 63358 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 4
   [junit4]   2> 63360 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63360 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1c89bf6 commitCommandVersion:0
   [junit4]   2> 63383 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2122ab8f[collection1] main]
   [junit4]   2> 63387 INFO  (searcherExecutor-90-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2122ab8f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442415979}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416132}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416192}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 63393 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 63393 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 38
   [junit4]   2> 63393 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:3&fl=test_d_dvo&wt=xml} hits=1 status=0 QTime=1
   [junit4]   2> 63454 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:3&fl=test_d_dvo,*&wt=xml} hits=1 status=0 QTime=53
   [junit4]   2> 63463 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:3&fl=*dvo&wt=xml} hits=1 status=0 QTime=5
   [junit4]   2> 63468 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:3&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63468 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:3&fl=test_d_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63499 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=7
   [junit4]   2> 63522 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[4]} 0 2
   [junit4]   2> 63522 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63522 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1c89bf6 commitCommandVersion:0
   [junit4]   2> 63578 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@29a9a399[collection1] main]
   [junit4]   2> 63578 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 63580 INFO  (searcherExecutor-90-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@29a9a399[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442415979}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416132}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416192}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416352}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 63581 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 56
   [junit4]   2> 63582 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:4&fl=test_ls_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63593 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:4&fl=test_ls_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63610 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:4&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63617 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:4&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63619 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:4&fl=test_ls_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63622 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 63629 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[5]} 0 4
   [junit4]   2> 63631 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63631 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1c89bf6 commitCommandVersion:0
   [junit4]   2> 63673 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@41194d77[collection1] main]
   [junit4]   2> 63673 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 63676 INFO  (searcherExecutor-90-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@41194d77[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442415979}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416132}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416192}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416352}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416461}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 63680 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 49
   [junit4]   2> 63689 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:5&fl=test_f_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63691 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:5&fl=test_f_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63692 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:5&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63693 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:5&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63693 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:5&fl=test_f_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63693 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 63710 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[6]} 0 12
   [junit4]   2> 63711 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63711 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1c89bf6 commitCommandVersion:0
   [junit4]   2> 63927 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@737ee38[collection1] main]
   [junit4]   2> 63927 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 63933 INFO  (searcherExecutor-90-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@737ee38[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442415979}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416132}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416192}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416352}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416461}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416542}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 63934 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 222
   [junit4]   2> 63934 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:6&fl=test_dt_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63937 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:6&fl=test_dt_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63937 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:6&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63937 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:6&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63937 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:6&fl=test_dt_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 63942 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 63946 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[7]} 0 1
   [junit4]   2> 63947 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63947 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1c89bf6 commitCommandVersion:0
   [junit4]   2> 64188 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3ce488a6[collection1] main]
   [junit4]   2> 64188 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 64194 INFO  (searcherExecutor-90-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3ce488a6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442415979}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416132}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416192}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416352}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416461}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416542}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_8(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416777}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 64195 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 247
   [junit4]   2> 64195 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:7&fl=test_ss_dv&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 64195 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:7&fl=test_ss_dv,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 64202 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:7&fl=*_dv&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 64205 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:7&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 64208 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:7&fl=test_ss_dv,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 64211 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 64252 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[8]} 0 8
   [junit4]   2> 64253 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 64253 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1c89bf6 commitCommandVersion:0
   [junit4]   2> 64496 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3252fb70[collection1] main]
   [junit4]   2> 64500 INFO  (searcherExecutor-90-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3252fb70[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442415979}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416132}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416192}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416352}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1553442416461}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(9.0.0):c1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=9.0.0, os.arch=amd64, java.run

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

lient sessionid 0x10038b356620005, likely client has closed socket
   [junit4]   2> 214845 INFO  (closeThreadPool-5378-thread-1) [    ] o.a.s.c.Overseer Overseer (id=72119936937492484-127.0.0.1:61284_khlo%2Ffy-n_0000000000) closing
   [junit4]   2> 214845 INFO  (closeThreadPool-5366-thread-2) [    ] o.a.s.c.Overseer Overseer (id=72119936937492484-127.0.0.1:61284_khlo%2Ffy-n_0000000000) closing
   [junit4]   2> 214854 INFO  (closeThreadPool-5366-thread-2) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@5039ac28{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
   [junit4]   2> 214856 INFO  (closeThreadPool-5366-thread-9) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@735e035a{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
   [junit4]   2> 214856 INFO  (closeThreadPool-5366-thread-7) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@1e5e5898{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
   [junit4]   2> 214854 INFO  (closeThreadPool-5366-thread-10) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@3b4ba0b4{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
   [junit4]   2> 214857 INFO  (closeThreadPool-5366-thread-2) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4b50c22d{/khlo/fy,null,UNAVAILABLE}
   [junit4]   2> 214858 INFO  (closeThreadPool-5366-thread-9) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@20211cd4{/khlo/fy,null,UNAVAILABLE}
   [junit4]   2> 214858 INFO  (closeThreadPool-5366-thread-10) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@40f0844e{/khlo/fy,null,UNAVAILABLE}
   [junit4]   2> 214859 INFO  (closeThreadPool-5366-thread-7) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5a5c66d{/khlo/fy,null,UNAVAILABLE}
   [junit4]   2> 214859 INFO  (closeThreadPool-5366-thread-2) [    ] o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 214860 INFO  (closeThreadPool-5366-thread-7) [    ] o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 214860 INFO  (closeThreadPool-5366-thread-9) [    ] o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 214860 INFO  (closeThreadPool-5366-thread-10) [    ] o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 214863 INFO  (TEST-TestSQLHandler.doTest-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
   [junit4]   2> 214869 WARN  (ZkTestServer Run Thread) [    ] o.a.s.c.ZkTestServer Watch limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2> 	29	/solr/aliases.json
   [junit4]   2> 	18	/solr/collections/collection1/state.json
   [junit4]   2> 	7	/solr/collections/collection1/terms/shard1
   [junit4]   2> 	7	/solr/collections/collection1/terms/shard2
   [junit4]   2> 	5	/solr/security.json
   [junit4]   2> 	5	/solr/configs/conf1
   [junit4]   2> 	3	/solr/collections/control_collection/terms/shard1
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2> 	29	/solr/clusterstate.json
   [junit4]   2> 	29	/solr/clusterprops.json
   [junit4]   2> 	9	/solr/collections/control_collection/state.json
   [junit4]   2> 	2	/solr/overseer_elect/election/72119936937492490-127.0.0.1:61397_khlo%2Ffy-n_0000000001
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2> 	29	/solr/live_nodes
   [junit4]   2> 	29	/solr/collections
   [junit4]   2> 
   [junit4]   2> 214877 INFO  (TEST-TestSQLHandler.doTest-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:61276
   [junit4]   2> 214877 INFO  (TEST-TestSQLHandler.doTest-seed#[2ECAF1B27020E0BB]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 61276
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSQLHandler -Dtests.method=doTest -Dtests.seed=2ECAF1B27020E0BB -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=am-ET -Dtests.timezone=Asia/Tehran -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   20.0s J1 | TestSQLHandler.doTest <<<
   [junit4]    > Throwable #1: java.io.IOException: --> http://127.0.0.1:61397/khlo/fy/collection1_shard2_replica_n2:Failed to execute sqlQuery 'select id, field_i, str_s, field_i_p, field_f_p, field_d_p, field_l_p from collection1 where (text='(XXXX)' OR text='XXXX') AND text='XXXX' order by field_i desc' against JDBC connection 'jdbc:calcitesolr:'.
   [junit4]    > Error while executing SQL "select id, field_i, str_s, field_i_p, field_f_p, field_d_p, field_l_p from collection1 where (text='(XXXX)' OR text='XXXX') AND text='XXXX' order by field_i desc": java.io.IOException: java.util.concurrent.ExecutionException: java.io.IOException: --> http://127.0.0.1:61397/khlo/fy/collection1_shard2_replica_n2/:can not sort on a field w/o docValues unless it is indexed=true uninvertible=true and the type supports Uninversion: field_i
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([2ECAF1B27020E0BB:898E49161D9BF302]:0)
   [junit4]    > 	at org.apache.solr.client.solrj.io.stream.SolrStream.read(SolrStream.java:215)
   [junit4]    > 	at org.apache.solr.handler.TestSQLHandler.getTuples(TestSQLHandler.java:2517)
   [junit4]    > 	at org.apache.solr.handler.TestSQLHandler.testBasicSelect(TestSQLHandler.java:148)
   [junit4]    > 	at org.apache.solr.handler.TestSQLHandler.doTest(TestSQLHandler.java:99)
   [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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
   [junit4]    > 	at java.base/java.lang.Thread.run(Thread.java:844)
   [junit4]   2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_2ECAF1B27020E0BB-002
   [junit4]   2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=6, maxDocsPerChunk=9, blockSize=83), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=6, blockSize=83)), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@23f6142c), locale=am-ET, timezone=Asia/Tehran
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 9.0.4 (64-bit)/cpus=3,threads=1,free=172551344,total=380633088
   [junit4]   2> NOTE: All tests run in this JVM: [TestSQLHandler, TestSQLHandler, TestUseDocValuesAsStored, TestUseDocValuesAsStored, TestSQLHandler]
   [junit4] Completed [10/10 (3!)] on J1 in 20.22s, 1 test, 1 error <<< FAILURES!

[...truncated 12 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1573: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1100: There were test failures: 10 suites, 40 tests, 3 errors [seed: 2ECAF1B27020E0BB]

Total time: 3 minutes 40 seconds

[repro] Setting last failure code to 1

[repro] Failures:
[repro]   0/5 failed: org.apache.solr.schema.TestUseDocValuesAsStored
[repro]   3/5 failed: org.apache.solr.handler.TestSQLHandler
[repro] Exiting with code 1

C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST lucene\build move /y lucene\build lucene\build.repro 
        1 dir(s) moved.

C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST solr\build move /y solr\build solr\build.repro 
        1 dir(s) moved.

C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST lucene\build.orig move /y lucene\build.orig lucene\build 
        1 dir(s) moved.

C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST solr\build.orig move /y solr\build.orig solr\build 
        1 dir(s) moved.

C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>exit 0 
Archiving artifacts
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=bca22d58e2d126ec6d349d375d3ea028892104e1, workspace=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows
[WARNINGS] Computing warning deltas based on reference build #7827
Recording test results
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2