[JENKINS-EA] Lucene-Solr-master-Windows (64bit/jdk-13-ea+26) - Build # 8049 - Unstable!

[Policeman Jenkins Server]

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8049/
Java: 64bit/jdk-13-ea+26 -XX:-UseCompressedOops -XX:+UseG1GC

2 tests failed.
FAILED:  
org.apache.solr.schema.TestUseDocValuesAsStored.testDuplicateMultiValued

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
        at 
__randomizedtesting.SeedInfo.seed([129EC41BDE9B22E:EFF4F86173584492]:0)
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:947)
        at 
org.apache.solr.schema.TestUseDocValuesAsStored.doTest(TestUseDocValuesAsStored.java:367)
        at 
org.apache.solr.schema.TestUseDocValuesAsStored.testDuplicateMultiValued(TestUseDocValuesAsStored.java:165)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:567)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.base/java.lang.Thread.run(Thread.java:830)
Caused by: java.lang.RuntimeException: REQUEST FAILED: 
xpath=//arr[@name='test_ss_dv']/str[.='X']
        xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int 
name="QTime">0</int></lst><result name="response" numFound="11" 
start="0"><doc><str name="id">82</str><str 
name="test_s_dvo">󠁲󠁅󠁫󠁜󠀹󠀷󠀄󠀣󠀲󠁔󠀒󠁚󠁗󠁢󠁮󠀷󠁘󠀩󠁼󠁪</str></doc><doc><str 
name="id">83</str><arr 
name="test_is_dvo"><int>239207083</int><int>315631185</int><int>335764661</int><int>939932574</int></arr></doc><doc><str
 name="id">84</str><double 
name="test_d_dvo">-5.769465809832509E204</double></doc><doc><str 
name="id">85</str><long 
name="test_l_dvo">8822458579275712396</long></doc><doc><str 
name="id">86</str><float 
name="test_f_dvo">-5.4484336E-33</float></doc><doc><str name="id">87</str><arr 
name="test_dts_dvo"><date>-7028-06-01T05:06:41.241Z</date><date>-6241-11-06T14:21:14.960Z</date><date>-1906-10-29T18:20:23.452Z</date><date>2037-02-23T08:54:03.998Z</date><date>2427-05-15T03:57:35.386Z</date><date>6585-05-20T19:37:05.952Z</date><date>8235-03-31T14:05:30.813Z</date></arr></doc><doc><str
 name="id">88</str><str name="test_s_dv">ࠆࠣࠨ࠸ࠔࠍ࠶ࠩࠏ࠲ࠜࠩࠦࠟ</str></doc><doc><str 
name="id">89</str><str name="test_s_dvo2">䷫䷛䷝䷲䷋䷱䷒䷦䷜䷖䷉䷘</str></doc><doc><str 
name="id">90</str><arr 
name="enums_dvo"><str>x6</str><str>x8</str><str>x10</str><str>x17</str></arr></doc><doc><str
 name="id">1</str><arr 
name="test_ss_dvo"><str>X</str><str>Y</str></arr></doc></result>
</response>

        request was:q=*:*&fl=*&wt=xml
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:940)
        ... 40 more


FAILED:  
org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testClassifyStream

Error Message:
expected:<0.0> but was:<0.9998245650830389>

Stack Trace:
java.lang.AssertionError: expected:<0.0> but was:<0.9998245650830389>
        at 
__randomizedtesting.SeedInfo.seed([23BBDFC7F597AAA:A77327C44601633E]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at org.junit.Assert.failNotEquals(Assert.java:834)
        at org.junit.Assert.assertEquals(Assert.java:553)
        at org.junit.Assert.assertEquals(Assert.java:683)
        at 
org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testClassifyStream(StreamDecoratorTest.java:3680)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:567)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.base/java.lang.Thread.run(Thread.java:830)




Build Log:
[...truncated 13771 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_129EC41BDE9B22E-001\init-core-data-001
   [junit4]   2> 1256714 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[129EC41BDE9B22E]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 1256723 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[129EC41BDE9B22E]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 1256723 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[129EC41BDE9B22E]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 1256726 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.SolrTestCaseJ4 ###Starting testUseDocValuesAsStoredFalse
   [junit4]   2> 1256743 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1256750 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 1256759 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] 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> 1256763 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1256770 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 1256771 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] 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_129EC41BDE9B22E-001\tempDir-001\collection1\conf\managed-schema
   [junit4]   2> 1256776 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] 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_129EC41BDE9B22E-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_129EC41BDE9B22E-001\tempDir-001\collection1\conf\schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1256981 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1256983 WARN  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@3285bf92[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1256983 WARN  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@3285bf92[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1256986 WARN  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@555e5152[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1256986 WARN  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@555e5152[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1257009 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1257009 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics 
history in memory.
   [junit4]   2> 1257027 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@867d540
   [junit4]   2> 1257033 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@867d540
   [junit4]   2> 1257033 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@867d540
   [junit4]   2> 1257041 INFO  (coreLoadExecutor-5220-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 1257049 INFO  (coreLoadExecutor-5220-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 1257054 INFO  (coreLoadExecutor-5220-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 1257054 INFO  (coreLoadExecutor-5220-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_129EC41BDE9B22E-001\tempDir-001\collection1,
 trusted=true
   [junit4]   2> 1257055 INFO  (coreLoadExecutor-5220-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@867d540
   [junit4]   2> 1257055 INFO  (coreLoadExecutor-5220-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_129EC41BDE9B22E-001\tempDir-001\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\init-core-data-001\]
   [junit4]   2> 1257059 INFO  (coreLoadExecutor-5220-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=32, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=39.119140625, 
floorSegmentMB=1.7509765625, forceMergeDeletesPctAllowed=4.241913668913729, 
segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, 
deletesPctAllowed=30.325865424344283
   [junit4]   2> 1257066 WARN  (coreLoadExecutor-5220-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> 1257130 INFO  (coreLoadExecutor-5220-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1257130 INFO  (coreLoadExecutor-5220-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1257132 INFO  (coreLoadExecutor-5220-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=24, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=12.037109375, 
floorSegmentMB=0.2724609375, forceMergeDeletesPctAllowed=23.07584550032793, 
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.623424038227104, deletesPctAllowed=46.59067462987774
   [junit4]   2> 1257132 INFO  (coreLoadExecutor-5220-thread-1) [    
x:collection1 ] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@76f2b6bf[collection1] main]
   [junit4]   2> 1257132 INFO  (coreLoadExecutor-5220-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_129EC41BDE9B22E-001\tempDir-001\collection1\conf
   [junit4]   2> 1257133 INFO  (coreLoadExecutor-5220-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1257134 INFO  
(searcherExecutor-5221-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@76f2b6bf[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1257134 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1257136 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid]} 0 0
   [junit4]   2> 1257137 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1257137 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@467658ec commitCommandVersion:0
   [junit4]   2> 1257171 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4fe2e44d[collection1] main]
   [junit4]   2> 1257171 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1257171 INFO  (searcherExecutor-5221-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4fe2e44d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26,
 java.version=13-ea, timestamp=1562895574006, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1257172 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 38
   [junit4]   2> 1257172 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1257173 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1257173 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid&fl=id,nonstored_dv_*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1257173 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid&fl=id,nonstored_dv_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1257174 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1257174 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] 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> 1257174 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1257174 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2103487407
   [junit4]   2> 1257175 INFO  (coreCloseExecutor-5225-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@aff6dd3
   [junit4]   2> 1257175 INFO  (coreCloseExecutor-5225-thread-1) [    
x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=aff6dd3
   [junit4]   2> 1257175 INFO  (coreCloseExecutor-5225-thread-1) [    
x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@7ef82fe7: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@1f6c39f2
   [junit4]   2> 1257183 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.node, tag=null
   [junit4]   2> 1257184 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@5f3755f8: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@37d6a87d
   [junit4]   2> 1257188 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jvm, tag=null
   [junit4]   2> 1257188 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@58baacaf: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@5197c343
   [junit4]   2> 1257190 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jetty, tag=null
   [junit4]   2> 1257190 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@408a3dc1: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@58f7e11d
   [junit4]   2> 1257192 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E])
 [     ] o.a.s.SolrTestCaseJ4 ###Ending testUseDocValuesAsStoredFalse
   [junit4]   2> 1257195 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.SolrTestCaseJ4 ###Starting testManagedSchema
   [junit4]   2> 1257209 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1257215 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 1257222 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] 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> 1257223 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1257230 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 1257230 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] 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_129EC41BDE9B22E-001\tempDir-002\collection1\conf\managed-schema
   [junit4]   2> 1257233 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] 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_129EC41BDE9B22E-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_129EC41BDE9B22E-001\tempDir-002\collection1\conf\schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1257410 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1257412 WARN  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@25e0b2ca[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1257412 WARN  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@25e0b2ca[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1257414 WARN  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@6b2b426c[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1257414 WARN  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@6b2b426c[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1257435 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1257435 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics 
history in memory.
   [junit4]   2> 1257452 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@867d540
   [junit4]   2> 1257458 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@867d540
   [junit4]   2> 1257458 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@867d540
   [junit4]   2> 1257464 INFO  (coreLoadExecutor-5235-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 1257471 INFO  (coreLoadExecutor-5235-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 1257475 INFO  (coreLoadExecutor-5235-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 1257475 INFO  (coreLoadExecutor-5235-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_129EC41BDE9B22E-001\tempDir-002\collection1,
 trusted=true
   [junit4]   2> 1257476 INFO  (coreLoadExecutor-5235-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@867d540
   [junit4]   2> 1257476 INFO  (coreLoadExecutor-5235-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_129EC41BDE9B22E-001\tempDir-002\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\init-core-data-001\]
   [junit4]   2> 1257479 WARN  (coreLoadExecutor-5235-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> 1257528 INFO  (coreLoadExecutor-5235-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1257528 INFO  (coreLoadExecutor-5235-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1257533 INFO  (coreLoadExecutor-5235-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=32, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=39.119140625, 
floorSegmentMB=1.7509765625, forceMergeDeletesPctAllowed=4.241913668913729, 
segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, 
deletesPctAllowed=30.325865424344283
   [junit4]   2> 1257533 INFO  (coreLoadExecutor-5235-thread-1) [    
x:collection1 ] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@5ab3913b[collection1] main]
   [junit4]   2> 1257535 INFO  (coreLoadExecutor-5235-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_129EC41BDE9B22E-001\tempDir-002\collection1\conf
   [junit4]   2> 1257535 INFO  (coreLoadExecutor-5235-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1257536 INFO  
(searcherExecutor-5236-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5ab3913b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26,
 java.version=13-ea, timestamp=1562895574006, os=Windows 10, java.vendor=Oracle 
Corporation, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1257537 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1257537 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] 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_129EC41BDE9B22E-001\tempDir-002\collection1\conf\managed-schema
   [junit4]   2> 1257542 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] 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_129EC41BDE9B22E-001\tempDir-002\collection1\conf\managed-schema
   [junit4]   2> 1257545 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1257545 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] 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> 1257546 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[myid1]} 0 0
   [junit4]   2> 1257546 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1257546 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1d74a6bc commitCommandVersion:0
   [junit4]   2> 1257579 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.s.SolrIndexSearcher Opening [Searcher@3f5bc9d2[collection1] main]
   [junit4]   2> 1257579 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1257582 INFO  (searcherExecutor-5236-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3f5bc9d2[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26,
 java.version=13-ea, timestamp=1562895574415, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1257583 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 40
   [junit4]   2> 1257587 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid*&fl=*&wt=xml} hits=1 status=0 QTime=2
   [junit4]   2> 1257587 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1257587 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] 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> 1257587 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1257587 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1445341495
   [junit4]   2> 1257588 INFO  (coreCloseExecutor-5240-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@e6e5754
   [junit4]   2> 1257588 INFO  (coreCloseExecutor-5240-thread-1) [    
x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=e6e5754
   [junit4]   2> 1257588 INFO  (coreCloseExecutor-5240-thread-1) [    
x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@7a6d9049: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@77a8dc64
   [junit4]   2> 1257602 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1257602 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@4f38675: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@587ef812
   [junit4]   2> 1257606 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 1257606 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@2717ef95: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@5197c343
   [junit4]   2> 1257611 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 1257611 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@43258e99: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@58f7e11d
   [junit4]   2> 1257615 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [     
] o.a.s.SolrTestCaseJ4 ###Ending testManagedSchema
   [junit4]   2> 1257618 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued
   [junit4]   2> 1257629 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1257636 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 1257642 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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> 1257645 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1257650 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 1257652 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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_129EC41BDE9B22E-001\tempDir-003\collection1\conf\managed-schema
   [junit4]   2> 1257655 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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_129EC41BDE9B22E-001\tempDir-003\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_129EC41BDE9B22E-001\tempDir-003\collection1\conf\schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1257844 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1257845 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@4889e0e9[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1257846 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@4889e0e9[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1257849 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@7f17ebb[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1257849 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@7f17ebb[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1257878 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1257878 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics 
history in memory.
   [junit4]   2> 1257902 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@867d540
   [junit4]   2> 1257911 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@867d540
   [junit4]   2> 1257913 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@867d540
   [junit4]   2> 1257924 INFO  (coreLoadExecutor-5250-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 1257935 INFO  (coreLoadExecutor-5250-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 1257942 INFO  (coreLoadExecutor-5250-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 1257942 INFO  (coreLoadExecutor-5250-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_129EC41BDE9B22E-001\tempDir-003\collection1,
 trusted=true
   [junit4]   2> 1257942 INFO  (coreLoadExecutor-5250-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@867d540
   [junit4]   2> 1257942 INFO  (coreLoadExecutor-5250-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_129EC41BDE9B22E-001\tempDir-003\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\init-core-data-001\]
   [junit4]   2> 1257949 WARN  (coreLoadExecutor-5250-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> 1258040 INFO  (coreLoadExecutor-5250-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1258040 INFO  (coreLoadExecutor-5250-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1258042 INFO  (coreLoadExecutor-5250-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=32, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=39.119140625, 
floorSegmentMB=1.7509765625, forceMergeDeletesPctAllowed=4.241913668913729, 
segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, 
deletesPctAllowed=30.325865424344283
   [junit4]   2> 1258044 INFO  (coreLoadExecutor-5250-thread-1) [    
x:collection1 ] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@463fe626[collection1] main]
   [junit4]   2> 1258046 INFO  (coreLoadExecutor-5250-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_129EC41BDE9B22E-001\tempDir-003\collection1\conf
   [junit4]   2> 1258047 INFO  (coreLoadExecutor-5250-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1258047 INFO  
(searcherExecutor-5251-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@463fe626[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26,
 java.version=13-ea, timestamp=1562895574415, os=Windows 10, java.vendor=Oracle 
Corporation, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1258049 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1258050 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1258050 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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> 1258052 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 0
   [junit4]   2> 1258052 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1258052 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4b96a12c commitCommandVersion:0
   [junit4]   2> 1258099 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@29e1105c[collection1] main]
   [junit4]   2> 1258101 INFO  (searcherExecutor-5251-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@29e1105c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26,
 java.version=13-ea, timestamp=1562895574924, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1258101 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1258101 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 54
   [junit4]   2> 1258102 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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> 1258103 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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> 1258113 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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> 1258115 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1258117 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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=0
   [junit4]   2> 1258118 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1258120 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 0
   [junit4]   2> 1258121 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1258121 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4b96a12c commitCommandVersion:0
   [junit4]   2> 1258151 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@44c44243[collection1] main]
   [junit4]   2> 1258151 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1258152 INFO  (searcherExecutor-5251-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@44c44243[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26,
 java.version=13-ea, timestamp=1562895574924, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_3(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895574979, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1258153 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 35
   [junit4]   2> 1258153 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_is_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1258154 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_is_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1258154 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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> 1258154 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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> 1258157 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_is_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1258158 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 1258160 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 0
   [junit4]   2> 1258160 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1258160 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4b96a12c commitCommandVersion:0
   [junit4]   2> 1258180 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6b28236[collection1] main]
   [junit4]   2> 1258181 INFO  (searcherExecutor-5251-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6b28236[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26,
 java.version=13-ea, timestamp=1562895574924, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_3(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895574979, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_4(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575017, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1258181 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1258181 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 25
   [junit4]   2> 1258181 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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=0
   [junit4]   2> 1258185 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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=0
   [junit4]   2> 1258185 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1258186 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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> 1258186 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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=1
   [junit4]   2> 1258186 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 1258189 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[4]} 0 0
   [junit4]   2> 1258189 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1258189 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4b96a12c commitCommandVersion:0
   [junit4]   2> 1258210 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@10a70[collection1] main]
   [junit4]   2> 1258210 INFO  (searcherExecutor-5251-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@10a70[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26,
 java.version=13-ea, timestamp=1562895574924, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_3(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895574979, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_4(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575017, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575047, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1258210 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1258210 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 24
   [junit4]   2> 1258231 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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=21
   [junit4]   2> 1258233 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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> 1258233 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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> 1258235 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=*&wt=xml} hits=1 status=0 QTime=1
   [junit4]   2> 1258236 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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> 1258237 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 1258239 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 0
   [junit4]   2> 1258239 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1258239 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4b96a12c commitCommandVersion:0
   [junit4]   2> 1258269 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@72680bf8[collection1] main]
   [junit4]   2> 1258269 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1258269 INFO  (searcherExecutor-5251-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@72680bf8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26,
 java.version=13-ea, timestamp=1562895574924, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_3(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895574979, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_4(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575017, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575047, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_6(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575097, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1258269 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 35
   [junit4]   2> 1258272 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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> 1258273 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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> 1258273 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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> 1258273 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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> 1258274 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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> 1258274 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1258298 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[6]} 0 0
   [junit4]   2> 1258298 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1258298 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4b96a12c commitCommandVersion:0
   [junit4]   2> 1258364 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@c933d5c[collection1] main]
   [junit4]   2> 1258365 INFO  (searcherExecutor-5251-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@c933d5c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26,
 java.version=13-ea, timestamp=1562895574924, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_3(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895574979, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_4(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575017, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575047, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_6(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575097, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_7(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575201, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1258366 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1258366 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 74
   [junit4]   2> 1258366 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=test_dts_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1258366 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=test_dts_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1258428 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=*dvo&wt=xml} hits=1 status=0 QTime=67
   [junit4]   2> 1258466 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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> 1258468 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=test_dts_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1258468 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 1258471 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[7]} 0 0
   [junit4]   2> 1258471 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1258471 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4b96a12c commitCommandVersion:0
   [junit4]   2> 1258495 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@14e998fe[collection1] main]
   [junit4]   2> 1258495 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1258495 INFO  (searcherExecutor-5251-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@14e998fe[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26,
 java.version=13-ea, timestamp=1562895574924, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_3(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895574979, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_4(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575017, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575047, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_6(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575097, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_7(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575201, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_8(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575328, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1258497 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 28
   [junit4]   2> 1258497 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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> 1258497 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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> 1258499 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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> 1258501 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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> 1258502 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] 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> 1258503 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 1258505 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[8]} 0 0
   [junit4]   2> 1258505 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1258505 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4b96a12c commitCommandVersion:0
   [junit4]   2> 1258533 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a166dae[collection1] main]
   [junit4]   2> 1258533 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1258534 INFO  (searcherExecutor-5251-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1a166dae[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26,
 java.version=13-ea, timestamp=1562895574924, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_3(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895574979, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_4(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575017, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575047, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_6(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575097, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_7(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575201, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_8(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575328, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_9(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575370, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1258535 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 32
   [junit4]   2> 1258535 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_ss_dvo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1258535 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_ss_dvo2,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1258536 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=*vo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1258537 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1258538 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_ss_dvo2,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1258539 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
   [junit4]   2> 1258542 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[9]} 0 1
   [junit4]   2> 1258542 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1258542 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4b96a12c commitCommandVersion:0
   [junit4]   2> 1258566 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@cc540e8[collection1] main]
   [junit4]   2> 1258567 INFO  (searcherExecutor-5251-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@cc540e8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26,
 java.version=13-ea, timestamp=1562895574924, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_3(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895574979, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_4(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575017, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575047, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_6(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575097, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_7(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575201, java.vendor=Oracle Corporation, 
os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, 
source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_8(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, 
java.version=13-ea, timestamp=1562895575328, java.vendor=Ora

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

llection/terms/shard1
   [junit4]   2>        3       /solr/collections/mainCorpus/terms/shard2
   [junit4]   2>        3       /solr/collections/collection1/terms/shard1
   [junit4]   2>        3       /solr/collections/collection1/terms/shard2
   [junit4]   2>        3       /solr/collections/uknownCollection/terms/shard2
   [junit4]   2>        3       /solr/collections/uknownCollection/terms/shard1
   [junit4]   2>        3       /solr/collections/workQueue/terms/shard2
   [junit4]   2>        2       
/solr/collections/collection1/collectionprops.json
   [junit4]   2>        2       
/solr/collections/mainCorpus1/collectionprops.json
   [junit4]   2>        2       
/solr/collections/destinationCollection/collectionprops.json
   [junit4]   2>        2       /solr/collections/workQueue/collectionprops.json
   [junit4]   2>        2       
/solr/collections/destination/collectionprops.json
   [junit4]   2>        2       
/solr/collections/destination1/collectionprops.json
   [junit4]   2>        2       
/solr/collections/workQueue1/collectionprops.json
   [junit4]   2>        2       
/solr/collections/checkpointCollection/terms/shard2
   [junit4]   2>        2       
/solr/collections/modelCollection/collectionprops.json
   [junit4]   2>        2       
/solr/collections/uknownCollection/collectionprops.json
   [junit4]   2>        2       
/solr/collections/mainCorpus/collectionprops.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        75      
/solr/collections/parallelDestinationCollection1/state.json
   [junit4]   2>        72      /solr/clusterstate.json
   [junit4]   2>        43      
/solr/collections/parallelDestinationCollection/state.json
   [junit4]   2>        38      
/solr/collections/destinationCollection/state.json
   [junit4]   2>        27      /solr/collections/workQueue/state.json
   [junit4]   2>        27      /solr/collections/modelCollection/state.json
   [junit4]   2>        26      /solr/collections/collection1/state.json
   [junit4]   2>        25      /solr/collections/uknownCollection/state.json
   [junit4]   2>        25      
/solr/collections/checkpointCollection/state.json
   [junit4]   2>        20      /solr/collections/destination1/state.json
   [junit4]   2>        20      /solr/collections/mainCorpus/state.json
   [junit4]   2>        19      /solr/collections/mainCorpus1/state.json
   [junit4]   2>        19      /solr/collections/destination/state.json
   [junit4]   2>        19      /solr/collections/workQueue1/state.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        361     /solr/collections
   [junit4]   2>        247     /solr/overseer/queue
   [junit4]   2>        108     /solr/live_nodes
   [junit4]   2>        90      /solr/overseer/collection-queue-work
   [junit4]   2> 
   [junit4]   2> 462297 INFO  
(SUITE-StreamDecoratorTest-seed#[23BBDFC7F597AAA]-worker) [     ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:50593
   [junit4]   2> 462297 INFO  
(SUITE-StreamDecoratorTest-seed#[23BBDFC7F597AAA]-worker) [     ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 50593
   [junit4]   2> NOTE: leaving temporary files on disk at: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.io.stream.StreamDecoratorTest_23BBDFC7F597AAA-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene80): 
{expr_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 name_s=Lucene50(blocksize=128), terms_ss=PostingsFormat(name=Asserting), 
join2_s=PostingsFormat(name=Asserting), 
field_s=PostingsFormat(name=LuceneVarGapFixedInterval), 
multiDefault=PostingsFormat(name=Asserting), 
subject=PostingsFormat(name=Asserting), a1_s=PostingsFormat(name=Asserting), 
a2_s=Lucene50(blocksize=128), body_t=Lucene50(blocksize=128), 
s_multi=Lucene50(blocksize=128), 
a_ss=PostingsFormat(name=LuceneVarGapFixedInterval), 
a_s=Lucene50(blocksize=128), 
tv_text=PostingsFormat(name=LuceneVarGapFixedInterval), 
text_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 
id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 text=Lucene50(blocksize=128), bbid_s=Lucene50(blocksize=128), 
type_s=PostingsFormat(name=LuceneVarGapFixedInterval), 
checkpoint_ss=Lucene50(blocksize=128), test_t=Lucene50(blocksize=128), 
ident_s=PostingsFormat(name=Asserting), extra_s=PostingsFormat(name=Asserting), 
ykey_s=PostingsFormat(name=LuceneVarGapFixedInterval), 
side_s=Lucene50(blocksize=128)}, 
docValues:{expr_s=DocValuesFormat(name=Lucene80), 
name_s=DocValuesFormat(name=Asserting), 
iteration_i=DocValuesFormat(name=Lucene80), 
terms_ss=DocValuesFormat(name=Direct), join2_s=DocValuesFormat(name=Direct), 
field_s=DocValuesFormat(name=Lucene80), 
multiDefault=DocValuesFormat(name=Direct), a1_s=DocValuesFormat(name=Direct), 
a_f=DocValuesFormat(name=Direct), intDefault=DocValuesFormat(name=Lucene80), 
a2_s=DocValuesFormat(name=Asserting), join1_i=DocValuesFormat(name=Lucene80), 
falseNegative_i=DocValuesFormat(name=Lucene80), 
a_i=DocValuesFormat(name=Lucene80), test_i=DocValuesFormat(name=Direct), 
join3_i=DocValuesFormat(name=Asserting), 
falsePositive_i=DocValuesFormat(name=Lucene80), 
field_i=DocValuesFormat(name=Direct), s_multi=DocValuesFormat(name=Asserting), 
a_ss=DocValuesFormat(name=Lucene80), a_s=DocValuesFormat(name=Asserting), 
text_s=DocValuesFormat(name=Lucene80), id=DocValuesFormat(name=Lucene80), 
bbid_s=DocValuesFormat(name=Asserting), 
timestamp=DocValuesFormat(name=Lucene80), 
idfs_ds=DocValuesFormat(name=Lucene80), type_s=DocValuesFormat(name=Lucene80), 
alpha_d=DocValuesFormat(name=Asserting), 
i_multi=DocValuesFormat(name=Lucene80), 
weights_ds=DocValuesFormat(name=Asserting), 
trueNegative_i=DocValuesFormat(name=Asserting), 
b_ls=DocValuesFormat(name=Direct), out_i=DocValuesFormat(name=Direct), 
checkpoint_ss=DocValuesFormat(name=Asserting), 
ident_s=DocValuesFormat(name=Direct), _version_=DocValuesFormat(name=Lucene80), 
error_d=DocValuesFormat(name=Lucene80), extra_s=DocValuesFormat(name=Direct), 
ykey_s=DocValuesFormat(name=Lucene80), side_s=DocValuesFormat(name=Asserting), 
truePositive_i=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1045, 
maxMBSortInHeap=7.443360791832037, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@bb9ac88),
 locale=lu, timezone=Asia/Qyzylorda
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 13-ea 
(64-bit)/cpus=3,threads=1,free=267555456,total=455081984
   [junit4]   2> NOTE: All tests run in this JVM: [FieldValueEvaluatorTest, 
TestFastWriter, ModuloEvaluatorTest, SquareRootEvaluatorTest, 
TestFastInputStream, OrEvaluatorTest, SolrExampleBinaryTest, 
TangentEvaluatorTest, SelectWithEvaluatorsTest, TestPolicy2Old, 
ArcCosineEvaluatorTest, SolrSchemalessExampleTest, 
TestCloudCollectionsListeners, ClientUtilsTest, TestLBHttp2SolrClient, 
GetByIdTest, TestV2Request, SolrParamTest, NaturalLogEvaluatorTest, 
TestClusteringResponse, TestSpellCheckResponse, ModifiableSolrParamsTest, 
TestUpdateRequestCodec, Http2SolrClientTest, JettyWebappTest, 
ConcurrentUpdateHttp2SolrClientBadInputTest, CorrelationEvaluatorTest, 
JsonValidatorTest, SchemaTest, NormalizeEvaluatorTest, TestCoreAdmin, 
TestEmbeddedSolrServer, CloudSolrClientCacheTest, AndEvaluatorTest, 
LBHttpSolrClientBadInputTest, QueryFacetMapTest, JdbcDriverTest, 
SolrExceptionTest, SolrExampleStreamingBinaryTest, TestNamedListCodec, 
MergeIndexesEmbeddedTest, DomainMapTest, StreamExpressionTest, 
CloudSolrClientRetryTest, RoundEvaluatorTest, CloudSolrClientBadInputTest, 
CloudHttp2SolrClientBuilderTest, StreamExpressionToExplanationTest, 
CubedRootEvaluatorTest, JsonRequestApiHeatmapFacetingTest, JsonRequestApiTest, 
UsingSolrJRefGuideExamplesTest, ZkConfigFilesTest, SolrExampleBinaryHttp2Test, 
SolrExampleXMLTest, SolrQueryTest, TestSolrJErrorHandling, TestPolicy, 
TestPolicy2, TestPolicyOld, LargeVolumeBinaryJettyTest, 
LargeVolumeEmbeddedTest, LargeVolumeJettyTest, SolrExampleEmbeddedTest, 
SolrExampleJettyTest, SolrExampleStreamingBinaryHttp2Test, 
SolrExampleStreamingTest, SolrExampleXMLHttp2Test, BasicHttpSolrClientTest, 
CloudHttp2SolrClientMultiConstructorTest, CloudHttp2SolrClientRetryTest, 
CloudSolrClientBuilderTest, ConcurrentUpdateHttp2SolrClientTest, 
ConcurrentUpdateSolrClientBadInputTest, ConcurrentUpdateSolrClientBuilderTest, 
ConcurrentUpdateSolrClientTest, Http2SolrClientCompatibilityTest, 
HttpSolrClientBadInputTest, HttpSolrClientBuilderTest, 
HttpSolrClientConPoolTest, LBHttpSolrClientBuilderTest, 
TestCloudSolrClientConnections, TestLang, GraphExpressionTest, JDBCStreamTest, 
StreamDecoratorTest]
   [junit4] Completed [166/199 (1!)] on J1 in 88.18s, 49 tests, 1 failure <<< 
FAILURES!

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

[repro] Revision: f85a78c44185c6cb620866a1ab37510757392ed0

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

[repro] ant clean

[...truncated 6 lines...]
[repro] Test suites by module:
[repro]    solr\core
[repro]       TestUseDocValuesAsStored
[repro]    solr\solrj
[repro]       StreamDecoratorTest
[repro] ant compile-test

[...truncated 3358 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 
-Dtests.class="*.TestUseDocValuesAsStored" -Dtests.showOutput=onerror 
"-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC" -Dtests.seed=129EC41BDE9B22E 
-Dtests.slow=true -Dtests.locale=ln-AO -Dtests.timezone=Etc/GMT0 
-Dtests.asserts=true -Dtests.file.encoding=Cp1252

[...truncated 103 lines...]
[repro] ant compile-test

[...truncated 454 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 
-Dtests.class="*.StreamDecoratorTest" -Dtests.showOutput=onerror 
"-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC" -Dtests.seed=23BBDFC7F597AAA 
-Dtests.slow=true -Dtests.locale=lu -Dtests.timezone=Asia/Qyzylorda 
-Dtests.asserts=true -Dtests.file.encoding=US-ASCII

[...truncated 313 lines...]
[repro] Failures:
[repro]   0/5 failed: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest
[repro]   0/5 failed: org.apache.solr.schema.TestUseDocValuesAsStored
[repro] Exiting with code 0

[...truncated 78 lines...]

---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org