[JENKINS] Lucene-Solr-8.x-Linux (64bit/jdk-11.0.3) - Build # 835 - Unstable!

[Policeman Jenkins Server]

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/835/
Java: 64bit/jdk-11.0.3 -XX:+UseCompressedOops -XX:+UseSerialGC

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

Error Message:
mismatch: 'myid1'!='myid' @ response/docs/[0]/id

Stack Trace:
java.lang.RuntimeException: mismatch: 'myid1'!='myid' @ response/docs/[0]/id
        at 
__randomizedtesting.SeedInfo.seed([5492CE219E1804D1:66B8C9BF66E62008]:0)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:1035)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:982)
        at 
org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults(TestUseDocValuesAsStored.java:255)
        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:566)
        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:834)


FAILED:  
org.apache.solr.schema.TestUseDocValuesAsStored.testDuplicateMultiValued

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
        at 
__randomizedtesting.SeedInfo.seed([5492CE219E1804D1:BA4FDA0150A9F26D]: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:164)
        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:566)
        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:834)
Caused by: java.lang.RuntimeException: REQUEST FAILED: 
xpath=//arr[@name='test_ss_dvo']/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="17" 
start="0"><doc><str name="id">262</str><arr 
name="test_ss_dvo"><str>汥琠葷葬玥繙</str><str>ꓒꓴꓮ</str><str>ꛊꛣꚸꚩ꛷ꛖꛌꛉꛁꛢꛉꛋꛒ꛺ꚺ꛲ꛚꚥ</str></arr></doc><doc><str
 name="id">263</str><int name="test_i_dvo">784672073</int></doc><doc><str 
name="id">264</str><double 
name="test_d_dvo">2.0810581938058295E-26</double></doc><doc><str 
name="id">265</str><long 
name="test_l_dvo">-5942286324001684341</long></doc><doc><str 
name="id">266</str><arr 
name="test_fs_dvo"><float>-3.11020585E10</float><float>-13.283813</float><float>-0.0020390744</float><float>-1.3303953E-8</float><float>4.8661044E-11</float><float>5.164787E-10</float><float>4.654424E-8</float><float>4.0034652E10</float><float>1.07592628E15</float><float>6.2009132E32</float></arr></doc><doc><str
 name="id">267</str><arr 
name="test_dts_dvo"><date>-2938-08-27T14:12:48.570Z</date><date>6363-03-02T22:10:08.699Z</date><date>6970-12-25T21:24:50.188Z</date><date>8342-01-23T00:33:37.676Z</date></arr></doc><doc><str
 name="id">268</str><arr 
name="test_ss_dv"><str>⟴⟿⟸⟺⟵⟰⟳⟾⟽⟷⟹⟰⟺⟸⟶⟶⟴⟿</str><str>ᰀᰈ᱄᱂</str><str>ᨍᨏ</str><str>ᧇ᧖ᧃᦏᦣᦆᦍᦶ</str><str>ᥱ᥺ᥧ</str><str>ⷍⷑⷍ⶯⷗ⶡⶅⶎⷄⶠⷉⶊⷂ⶛</str><str>𐃈𐃈𐂗𐃧𐃑𐃨𐃷𐃬</str><str>꟯ꜯꝎꝦꝽꞸꞕ꟥ꟻ</str><str>ἔἧὠἅὶ῱Ἳ῾ᾱ᾵Ἳὺ῰Ὕῴἦ</str><str>ⱻⱷⱱⱫ</str></arr></doc><doc><str
 name="id">269</str><str name="test_s_dvo2">▱◡◡▷▿▩</str></doc><doc><str 
name="id">270</str><str name="enum_dvo">High</str></doc><doc><str 
name="id">myid</str></doc></result>
</response>

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




Build Log:
[...truncated 15785 lines...]
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_5492CE219E1804D1-001/init-core-data-001
   [junit4]   2> 2235797 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
   [junit4]   2> 2235809 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2235811 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 2235815 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 2235816 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_5492CE219E1804D1-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 2235872 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_5492CE219E1804D1-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_5492CE219E1804D1-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 2235933 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 2235934 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@1ce0d27[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2235934 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@1ce0d27[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2235955 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@11de803e[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2235955 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@11de803e[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2235968 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 2235968 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics 
history in memory.
   [junit4]   2> 2235983 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c3df612
   [junit4]   2> 2235992 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c3df612
   [junit4]   2> 2235992 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c3df612
   [junit4]   2> 2235997 INFO  (coreLoadExecutor-12577-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
   [junit4]   2> 2236003 INFO  (coreLoadExecutor-12577-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 2236007 INFO  (coreLoadExecutor-12577-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 2236007 INFO  (coreLoadExecutor-12577-thread-1) [    
x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_5492CE219E1804D1-001/tempDir-001/collection1,
 trusted=true
   [junit4]   2> 2236007 INFO  (coreLoadExecutor-12577-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@2c3df612
   [junit4]   2> 2236007 INFO  (coreLoadExecutor-12577-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_5492CE219E1804D1-001/tempDir-001/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_5492CE219E1804D1-001/init-core-data-001/]
   [junit4]   2> 2236009 INFO  (coreLoadExecutor-12577-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=41, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.36660502045722965]
   [junit4]   2> 2236107 WARN  (coreLoadExecutor-12577-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> 2236143 INFO  (coreLoadExecutor-12577-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2236143 INFO  (coreLoadExecutor-12577-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2236144 INFO  (coreLoadExecutor-12577-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.532120899110497]
   [junit4]   2> 2236145 INFO  (coreLoadExecutor-12577-thread-1) [    
x:collection1 ] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@40884322[collection1] main]
   [junit4]   2> 2236145 INFO  (coreLoadExecutor-12577-thread-1) [    
x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized 
to use dir: 
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_5492CE219E1804D1-001/tempDir-001/collection1/conf
   [junit4]   2> 2236145 INFO  (coreLoadExecutor-12577-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2236146 INFO  
(searcherExecutor-12578-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@40884322[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2236146 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 2236146 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2236147 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 0
   [junit4]   2> 2236148 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2236148 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
   [junit4]   2> 2236464 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7db1e589[collection1] main]
   [junit4]   2> 2236464 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2236465 INFO  (searcherExecutor-12578-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7db1e589[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C1:[diagnostics={os=Linux,
 java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, 
lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, 
source=flush, os.version=4.18.0-24-generic, 
timestamp=1562493470554}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2236465 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 316
   [junit4]   2> 2236465 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2236466 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2236467 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2236469 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2236469 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2236470 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2236481 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 0
   [junit4]   2> 2236481 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2236481 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
   [junit4]   2> 2236871 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@164f7afb[collection1] main]
   [junit4]   2> 2236871 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2236872 INFO  (searcherExecutor-12578-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@164f7afb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C1:[diagnostics={os=Linux,
 java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, 
lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, 
source=flush, os.version=4.18.0-24-generic, 
timestamp=1562493470554}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_1(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493470887}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2236872 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 390
   [junit4]   2> 2236872 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2236872 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2236873 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2236873 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2236873 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2236873 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 2236874 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 0
   [junit4]   2> 2236874 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2236874 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
   [junit4]   2> 2237358 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@594ab60b[collection1] main]
   [junit4]   2> 2237359 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2237359 INFO  (searcherExecutor-12578-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@594ab60b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C1:[diagnostics={os=Linux,
 java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, 
lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, 
source=flush, os.version=4.18.0-24-generic, 
timestamp=1562493470554}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_1(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493470887}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_2(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493471279}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2237359 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 485
   [junit4]   2> 2237360 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_ds_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2237360 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_ds_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2237361 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2237362 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2237362 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_ds_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2237363 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 2237364 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[4]} 0 0
   [junit4]   2> 2237364 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2237364 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
   [junit4]   2> 2237962 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@261ab390[collection1] main]
   [junit4]   2> 2237962 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2237963 INFO  (searcherExecutor-12578-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@261ab390[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C1:[diagnostics={os=Linux,
 java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, 
lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, 
source=flush, os.version=4.18.0-24-generic, 
timestamp=1562493470554}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_1(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493470887}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_2(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493471279}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_3(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493471770}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2237963 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 598
   [junit4]   2> 2237964 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2237964 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2237965 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2237966 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2237967 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2237968 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 2237969 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 0
   [junit4]   2> 2237969 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2237970 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
   [junit4]   2> 2238242 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6adc7694[collection1] main]
   [junit4]   2> 2238242 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2238243 INFO  (searcherExecutor-12578-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6adc7694[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C1:[diagnostics={os=Linux,
 java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, 
lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, 
source=flush, os.version=4.18.0-24-generic, 
timestamp=1562493470554}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_1(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493470887}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_2(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493471279}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_3(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493471770}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_4(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493472375}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2238243 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 273
   [junit4]   2> 2238243 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_fs_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2238244 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_fs_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2238244 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2238244 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2238245 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_fs_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2238245 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 2238246 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[6]} 0 0
   [junit4]   2> 2238246 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2238246 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
   [junit4]   2> 2238372 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@90df86c[collection1] main]
   [junit4]   2> 2238372 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2238373 INFO  (searcherExecutor-12578-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@90df86c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C1:[diagnostics={os=Linux,
 java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, 
lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, 
source=flush, os.version=4.18.0-24-generic, 
timestamp=1562493470554}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_1(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493470887}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_2(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493471279}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_3(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493471770}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_4(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493472375}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493472651}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2238373 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 126
   [junit4]   2> 2238373 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2238374 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2238374 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2238374 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2238375 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2238375 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 2238376 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[7]} 0 0
   [junit4]   2> 2238376 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2238376 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
   [junit4]   2> 2238506 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2457511d[collection1] main]
   [junit4]   2> 2238506 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2238507 INFO  (searcherExecutor-12578-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2457511d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C1:[diagnostics={os=Linux,
 java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, 
lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, 
source=flush, os.version=4.18.0-24-generic, 
timestamp=1562493470554}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_1(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493470887}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_2(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493471279}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_3(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493471770}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_4(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493472375}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493472651}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_6(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493472781}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2238507 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 131
   [junit4]   2> 2238508 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_s_dv&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2238508 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_s_dv,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2238508 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2238508 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2238509 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_s_dv,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2238509 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 2238509 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[8]} 0 0
   [junit4]   2> 2238509 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2238510 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
   [junit4]   2> 2238691 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4e8fe76c[collection1] main]
   [junit4]   2> 2238692 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2238693 INFO  (searcherExecutor-12578-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4e8fe76c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C1:[diagnostics={os=Linux,
 java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, 
lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, 
source=flush, os.version=4.18.0-24-generic, 
timestamp=1562493470554}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_1(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493470887}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_2(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493471279}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_3(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493471770}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_4(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493472375}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493472651}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_6(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493472781}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_7(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493472915}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2238693 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 183
   [junit4]   2> 2238693 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_s_dvo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2238694 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_s_dvo2,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2238694 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2238695 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2238695 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_s_dvo2,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2238696 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
   [junit4]   2> 2238697 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[9]} 0 0
   [junit4]   2> 2238697 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2238697 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
   [junit4]   2> 2238970 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@39e5fc80[collection1] main]
   [junit4]   2> 2238970 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2238971 INFO  (searcherExecutor-12578-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@39e5fc80[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C1:[diagnostics={os=Linux,
 java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, 
lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, 
source=flush, os.version=4.18.0-24-generic, 
timestamp=1562493470554}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_1(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493470887}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_2(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493471279}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_3(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493471770}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_4(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493472375}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493472651}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_6(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493472781}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_7(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493472915}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_8(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493473102}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2238971 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 274
   [junit4]   2> 2238972 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=enums_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2238972 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=enums_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2238981 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2238982 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2238982 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=enums_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2238983 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=9 status=0 QTime=0
   [junit4]   2> 2238984 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 2238984 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] 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> 2238984 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[10]} 0 0
   [junit4]   2> 2238984 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2238984 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
   [junit4]   2> 2239044 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3bcb79b6[collection1] main]
   [junit4]   2> 2239044 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2239045 INFO  (searcherExecutor-12578-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3bcb79b6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(8.2.0):C1:[diagnostics={os=Linux,
 java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, 
lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, 
source=flush, os.version=4.18.0-24-generic, 
timestamp=1562493473389}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2239049 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 64
   [junit4]   2> 2239049 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=test_ss_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239050 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=test_ss_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239050 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239051 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239052 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=test_ss_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239052 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239053 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[11]} 0 0
   [junit4]   2> 2239053 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2239053 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
   [junit4]   2> 2239136 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@15bd5034[collection1] main]
   [junit4]   2> 2239137 INFO  (searcherExecutor-12578-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@15bd5034[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(8.2.0):C1:[diagnostics={os=Linux,
 java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, 
lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, 
source=flush, os.version=4.18.0-24-generic, 
timestamp=1562493473389}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_a(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493473458}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2239137 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2239138 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 84
   [junit4]   2> 2239138 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:11&fl=test_is_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239139 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:11&fl=test_is_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239139 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:11&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239139 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:11&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239140 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:11&fl=test_is_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239140 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 2239141 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[12]} 0 0
   [junit4]   2> 2239141 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2239141 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
   [junit4]   2> 2239241 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@682cfc6[collection1] main]
   [junit4]   2> 2239241 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2239242 INFO  (searcherExecutor-12578-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@682cfc6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(8.2.0):C1:[diagnostics={os=Linux,
 java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, 
lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, 
source=flush, os.version=4.18.0-24-generic, 
timestamp=1562493473389}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_a(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493473458}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_b(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493473546}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2239242 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 101
   [junit4]   2> 2239242 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=test_d_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239243 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=test_d_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239243 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239243 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239244 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=test_d_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239244 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 2239245 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[13]} 0 0
   [junit4]   2> 2239245 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2239245 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
   [junit4]   2> 2239349 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5d7d940[collection1] main]
   [junit4]   2> 2239349 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2239350 INFO  (searcherExecutor-12578-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5d7d940[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(8.2.0):C1:[diagnostics={os=Linux,
 java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, 
lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, 
source=flush, os.version=4.18.0-24-generic, 
timestamp=1562493473389}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_a(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493473458}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_b(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493473546}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_c(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493473650}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2239350 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 104
   [junit4]   2> 2239350 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:13&fl=test_ls_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239351 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:13&fl=test_ls_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239351 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:13&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239352 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:13&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239353 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:13&fl=test_ls_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239354 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 2239355 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[14]} 0 0
   [junit4]   2> 2239355 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2239355 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
   [junit4]   2> 2239472 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@559a4a46[collection1] main]
   [junit4]   2> 2239473 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2239473 INFO  (searcherExecutor-12578-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@559a4a46[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(8.2.0):C1:[diagnostics={os=Linux,
 java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, 
lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, 
source=flush, os.version=4.18.0-24-generic, 
timestamp=1562493473389}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_a(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493473458}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_b(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493473546}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_c(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493473650}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_d(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493473760}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2239473 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 118
   [junit4]   2> 2239474 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_fs_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239474 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_fs_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239474 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239475 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239475 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_fs_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239475 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 2239476 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[15]} 0 0
   [junit4]   2> 2239477 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2239477 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
   [junit4]   2> 2239616 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@60884de9[collection1] main]
   [junit4]   2> 2239618 INFO  (searcherExecutor-12578-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@60884de9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(8.2.0):C1:[diagnostics={os=Linux,
 java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, 
lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, 
source=flush, os.version=4.18.0-24-generic, 
timestamp=1562493473389}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_a(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493473458}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_b(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493473546}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_c(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493473650}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_d(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493473760}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_e(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493473882}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2239618 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2239618 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 141
   [junit4]   2> 2239618 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:15&fl=test_dt_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239619 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:15&fl=test_dt_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239619 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:15&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239619 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:15&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239620 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:15&fl=test_dt_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2239620 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 2239621 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[16]} 0 0
   [junit4]   2> 2239621 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2239621 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
   [junit4]   2> 2239850 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@51af2723[collection1] main]
   [junit4]   2> 2239850 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2239851 INFO  (searcherExecutor-12578-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@51af2723[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(8.2.0):C1:[diagnostics={os=Linux,
 java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, 
lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, 
source=flush, os.version=4.18.0-24-generic, 
timestamp=1562493473389}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_a(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493473458}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_b(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493473546}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_c(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493473650}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_d(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493473760}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_e(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-generic, 
timestamp=1562493473882}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_f(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, 
java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, 
os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, 
os.version=4.18.0-24-gene

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

7edb
   [junit4]   2> 2294748 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[5492CE219E1804D1]) 
[     ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jvm, tag=null
   [junit4]   2> 2294748 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[5492CE219E1804D1]) 
[     ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@1be01e26: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@27b2d165
   [junit4]   2> 2294752 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[5492CE219E1804D1]) 
[     ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jetty, tag=null
   [junit4]   2> 2294752 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[5492CE219E1804D1]) 
[     ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@66b5b680: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@2e0183d5
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_5492CE219E1804D1-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene80): {}, 
docValues:{}, maxPointsInLeafNode=622, maxMBSortInHeap=5.114769350133044, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@7f3e7fe1),
 locale=fr-KM, timezone=America/Grenada
   [junit4]   2> NOTE: Linux 4.18.0-24-generic amd64/AdoptOpenJDK 11.0.3 
(64-bit)/cpus=8,threads=1,free=146912216,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: 
[FieldAnalysisRequestHandlerTest, TestReloadAndDeleteDocs, 
PingRequestHandlerTest, UniqFieldsUpdateProcessorFactoryTest, 
TestLegacyNumericUtils, TestMacroExpander, TestJsonFacetsStatsParsing, 
MergeStrategyTest, HdfsDirectoryTest, IgnoreLargeDocumentProcessorFactoryTest, 
TestImplicitCoreProperties, TestRequestForwarding, 
SimpleCollectionCreateDeleteTest, TestDynamicFieldResource, 
TestFoldingMultitermQuery, AnalysisErrorHandlingTest, TestReloadDeadlock, 
CollectionStateFormat2Test, TestPointFields, TestInPlaceUpdatesDistrib, 
TestCursorMarkWithoutUniqueKey, CheckHdfsIndexTest, 
TestStressCloudBlindAtomicUpdates, TestCryptoKeys, AutoScalingHandlerTest, 
TestIBSimilarityFactory, ResponseHeaderTest, TestManagedSchemaAPI, 
TestDynamicLoading, NotRequiredUniqueKeyTest, PrimitiveFieldTypeTest, 
TestHashPartitioner, TestRangeQuery, AuditLoggerPluginTest, TestLockTree, 
TestSQLHandlerNonCloud, BlobRepositoryCloudTest, RecoveryAfterSoftCommitTest, 
TestSolrIndexConfig, TestDistributedTracing, TestApiFramework, 
TolerantUpdateProcessorTest, TestUtilizeNode, TestSimExtremeIndexing, 
DisMaxRequestHandlerTest, TestTlogReplica, TestDefaultStatsCache, 
TestIndexingPerformance, TestBlendedInfixSuggestions, 
TestTrackingShardHandlerFactory, PreAnalyzedUpdateProcessorTest, 
TestClassicSimilarityFactory, TestSolrCloudWithDelegationTokens, 
TestElisionMultitermQuery, TestSha256AuthenticationProvider, 
TestNoOpRegenerator, TestConfigSetsAPI, 
HdfsTlogReplayBufferedWhileIndexingTest, TestUtils, SuggesterFSTTest, 
RoutingToNodesWithPropertiesTest, TestManagedResource, 
DistributedFacetPivotSmallTest, TestHdfsCloudBackupRestore, TestZkChroot, 
SolrCoreCheckLockOnStartupTest, PhrasesIdentificationComponentTest, 
CdcrWithNodesRestartsTest, DocValuesTest, SchemaVersionSpecificBehaviorTest, 
TestHighlightDedupGrouping, TestSimpleQParserPlugin, TestFieldCacheWithThreads, 
LegacyCloudClusterPropTest, DirectSolrConnectionTest, ConfigSetsAPITest, 
NodeAddedTriggerTest, TokenizerChainTest, TestDistributedMap, 
HttpPartitionTest, SolrCoreMetricManagerTest, 
StatelessScriptUpdateProcessorFactoryTest, CloudMLTQParserTest, 
ZkControllerTest, TestMinHashQParser, TestFieldTypeCollectionResource, 
SimpleFacetsTest, CollectionsAPIAsyncDistributedZkTest, 
TestSimTriggerIntegration, DeleteNodeTest, SolrPluginUtilsTest, 
ProtectedTermFilterFactoryTest, SpellCheckCollatorTest, 
DistributedSpellCheckComponentTest, TestSimDistribStateManager, 
LoggingHandlerTest, TestCloudJSONFacetSKG, TestManagedSynonymFilterFactory, 
TestComponentsName, TestJavabinTupleStreamParser, SpellingQueryConverterTest, 
DistributedDebugComponentTest, TestFieldSortValues, SmileWriterTest, 
OrderedExecutorTest, SolrIndexMetricsTest, BasicFunctionalityTest, 
DirectSolrSpellCheckerTest, TestCorePropertiesReload, SearchHandlerTest, 
TestFaceting, TestSlowCompositeReaderWrapper, MetricTriggerIntegrationTest, 
TestCloudManagedSchema, TestStreamBody, RuleEngineTest, StressHdfsTest, 
VersionInfoTest, TestXmlQParser, TestNRTOpen, TriggerCooldownIntegrationTest, 
TestSolrCLIRunExample, BinaryUpdateRequestHandlerTest, 
CachingDirectoryFactoryTest, OutOfBoxZkACLAndCredentialsProvidersTest, 
TestAuthorizationFramework, ZkStateReaderTest, TestSystemCollAutoCreate, 
TestExactStatsCache, UUIDFieldTest, TestIndexSearcher, 
AtomicUpdateRemovalJavabinTest, MigrateRouteKeyTest, SynonymTokenizerTest, 
TestCollapseQParserPlugin, FacetPivotSmallTest, DirectoryFactoryTest, 
TestStressReorder, TestRequestStatusCollectionAPI, ConnectionReuseTest, 
TestNumericTerms64, EchoParamsTest, MinimalSchemaTest, OutputWriterTest, 
SampleTest, SolrInfoBeanTest, SolrTestCaseJ4Test, TestDistributedMissingSort, 
TestDistributedSearch, TestDocumentBuilder, TestRandomDVFaceting, 
TestRandomFaceting, TestSimpleTrackingShardHandler, TestSolrCoreProperties, 
TestTolerantSearch, TestTrie, TestDeprecatedFilters, TestLuceneMatchVersion, 
TestReversedWildcardFilterFactory, TestEmbeddedSolrServerAdminHandler, 
TestEmbeddedSolrServerConstructors, TestEmbeddedSolrServerSchemaAPI, 
AddReplicaTest, AliasIntegrationTest, AssignBackwardCompatibilityTest, 
BasicDistributedZk2Test, BasicDistributedZkTest, BasicZkTest, 
ChaosMonkeyNothingIsSafeTest, ChaosMonkeySafeLeaderTest, CleanupOldIndexTest, 
CloudExitableDirectoryReaderTest, CreateRoutedAliasTest, DeleteShardTest, 
DistribCursorPagingTest, DistribJoinFromCollectionTest, DistributedQueueTest, 
DistributedVersionInfoTest, LeaderElectionTest, 
LeaderFailoverAfterPartitionTest, LeaderTragicEventTest, 
MoveReplicaHDFSFailoverTest, MoveReplicaHDFSTest, MoveReplicaTest, 
OverseerRolesTest, OverseerTaskQueueTest, ReindexCollectionTest, 
SharedFSAutoReplicaFailoverTest, SliceStateTest, SolrCLIZkUtilsTest, 
SolrXmlInZkTest, SplitShardTest, TestAuthenticationFramework, 
TestCloudDeleteByQuery, TestCloudInspectUtil, 
TestCloudPhrasesIdentificationComponent, TestCloudPivotFacet, 
TestCloudRecovery, TestPrepRecovery, TestRandomFlRTGCloud, 
TestShortCircuitedRequests, VMParamsZkACLAndCredentialsProvidersTest, 
ZkSolrClientTest, ConcurrentDeleteAndCreateCollectionTest, 
CustomCollectionTest, NodeLostTriggerTest, ScheduledTriggerIntegrationTest, 
SystemLogListenerTest, TestSimClusterStateProvider, TestSimNodeAddedTrigger, 
TestSimNodeLostTrigger, BaseCdcrDistributedZkTest, CdcrBidirectionalTest, 
CdcrBootstrapTest, HdfsRecoveryZkTest, HdfsRestartWhileUpdatingTest, 
HdfsSyncSliceTest, HdfsDirectoryFactoryTest, RAMDirectoryFactoryTest, 
RequestHandlersTest, ResourceLoaderTest, TestBackupRepositoryFactory, 
TestBadConfig, TestConfig, TestConfigSetImmutable, TestJmxIntegration, 
TestSimpleTextCodec, TestSolrConfigHandler, TestConfigReload, 
TestReplicationHandler, TestConfigsApi, ZookeeperStatusHandlerTest, 
CustomTermsComponentTest, DistributedQueryComponentCustomSortTest, 
ShardsWhitelistTest, StatsComponentTest, TermVectorComponentTest, 
TermsComponentTest, TestDistributedStatsComponentCardinality, 
TestExpandComponent, TestHttpShardHandlerFactory, JavabinLoaderTest, 
RandomizedTaggerTest, TaggerTest, XmlInterpolationTest, HighlighterConfigTest, 
HighlighterTest, TestNumericTokenStream, TestLogWatcher, JvmMetricsTest, 
SolrMetricsIntegrationTest, SolrJmxReporterCloudTest, SolrShardReporterTest, 
TestCSVResponseWriter, TestCustomDocTransformer, TestGeoJSONResponseWriter, 
TestPushWriter, TestRawResponseWriter, TestRetrieveFieldsOptimizer, 
TestChildDocTransformerHierarchy, TestSubQueryTransformerCrossCore, 
TestBulkSchemaAPI, TestUniqueKeyFieldResource, ChangedSchemaMergeTest, 
CurrencyFieldTypeTest, ExternalFileFieldSortTest, IndexSchemaTest, 
TestCollationField, TestHalfAndHalfDocValues, TestSortableTextField, 
TestUseDocValuesAsStored]
   [junit4] Completed [751/867 (1!)] on J1 in 59.06s, 7 tests, 2 errors <<< 
FAILURES!

[...truncated 45206 lines...]
[repro] Jenkins log URL: 
https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/835/consoleText

[repro] Revision: 5d3a84fcd0f3d4bded24e6db0c78bbdcba6f3b2a

[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC"
[repro] Repro line:  ant test  -Dtestcase=TestUseDocValuesAsStored 
-Dtests.method=testMultipleSearchResults -Dtests.seed=5492CE219E1804D1 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr-KM 
-Dtests.timezone=America/Grenada -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII

[repro] Repro line:  ant test  -Dtestcase=TestUseDocValuesAsStored 
-Dtests.method=testDuplicateMultiValued -Dtests.seed=5492CE219E1804D1 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr-KM 
-Dtests.timezone=America/Grenada -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII

[repro] ant clean

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

[...truncated 3582 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 
-Dtests.class="*.TestUseDocValuesAsStored" -Dtests.showOutput=onerror 
"-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=5492CE219E1804D1 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr-KM 
-Dtests.timezone=America/Grenada -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII

[...truncated 119 lines...]
[repro] Failures:
[repro]   0/5 failed: org.apache.solr.schema.TestUseDocValuesAsStored
[repro] Exiting with code 0

[...truncated 43 lines...]

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