Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/20061/
Java: 64bit/jdk1.8.0_131 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC

2 tests failed.
FAILED:  org.apache.solr.cloud.MoveReplicaHDFSTest.test

Error Message:
Could not find collection : movereplicatest_coll

Stack Trace:
org.apache.solr.common.SolrException: Could not find collection : 
movereplicatest_coll
        at 
__randomizedtesting.SeedInfo.seed([41EB0B5C781BBEEC:C9BF3486D6E7D314]:0)
        at 
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:194)
        at 
org.apache.solr.cloud.MoveReplicaTest.getRandomReplica(MoveReplicaTest.java:185)
        at org.apache.solr.cloud.MoveReplicaTest.test(MoveReplicaTest.java:70)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        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:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        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 
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.lang.Thread.run(Thread.java:748)


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

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
        at 
__randomizedtesting.SeedInfo.seed([41EB0B5C781BBEEC:48B7F69D00C4AFD4]:0)
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:878)
        at 
org.apache.solr.schema.TestUseDocValuesAsStored.doTest(TestUseDocValuesAsStored.java:335)
        at 
org.apache.solr.schema.TestUseDocValuesAsStored.testRandomSingleAndMultiValued(TestUseDocValuesAsStored.java:176)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        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:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        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 
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.lang.Thread.run(Thread.java:748)
Caused by: java.lang.RuntimeException: REQUEST FAILED: 
xpath=*[count(//arr[@name='test_fs_dvo']/float) = 5]
        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="1" 
start="0"><doc><arr 
name="test_fs_dvo"><float>-7.5113335E32</float><float>-6.931654E22</float><float>-1.70372E-14</float><float>4.3711085E16</float><float>NaN</float><float>NaN</float></arr></doc></result>
</response>

        request was:q=id:59&fl=test_fs_dvo&wt=xml
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:871)
        ... 41 more




Build Log:
[...truncated 11559 lines...]
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_41EB0B5C781BBEEC-001/init-core-data-001
   [junit4]   2> 455651 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[41EB0B5C781BBEEC]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 455652 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[41EB0B5C781BBEEC]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 455653 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testOnEmptyIndex
   [junit4]   2> 455654 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 455659 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 455663 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
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> 455664 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 455672 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 455673 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_41EB0B5C781BBEEC-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 455685 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed 
the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_41EB0B5C781BBEEC-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_41EB0B5C781BBEEC-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 455702 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2cfd3e19
   [junit4]   2> 455705 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2cfd3e19
   [junit4]   2> 455705 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2cfd3e19
   [junit4]   2> 455711 INFO  (coreLoadExecutor-2064-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 455716 INFO  (coreLoadExecutor-2064-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 455719 INFO  (coreLoadExecutor-2064-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 455719 INFO  (coreLoadExecutor-2064-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_41EB0B5C781BBEEC-001/tempDir-001/collection1,
 trusted=true
   [junit4]   2> 455719 INFO  (coreLoadExecutor-2064-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@2cfd3e19
   [junit4]   2> 455719 INFO  (coreLoadExecutor-2064-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 455719 INFO  (coreLoadExecutor-2064-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_41EB0B5C781BBEEC-001/tempDir-001/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_41EB0B5C781BBEEC-001/init-core-data-001/]
   [junit4]   2> 455720 INFO  (coreLoadExecutor-2064-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=22, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=65.2919921875, 
floorSegmentMB=0.318359375, forceMergeDeletesPctAllowed=3.254896598883522, 
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7096697885185373
   [junit4]   2> 455730 WARN  (coreLoadExecutor-2064-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> 455749 INFO  (coreLoadExecutor-2064-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 455749 INFO  (coreLoadExecutor-2064-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 455749 INFO  (coreLoadExecutor-2064-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=50, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=33.1240234375, 
floorSegmentMB=1.677734375, forceMergeDeletesPctAllowed=5.7868992052843184, 
segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 455749 INFO  (coreLoadExecutor-2064-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@3bd3e2df[collection1] main]
   [junit4]   2> 455750 INFO  (coreLoadExecutor-2064-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_41EB0B5C781BBEEC-001/tempDir-001/collection1/conf
   [junit4]   2> 455750 INFO  (coreLoadExecutor-2064-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 455751 INFO  
(searcherExecutor-2065-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3bd3e2df[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 455751 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 455751 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 455751 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
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> 455751 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 455751 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7099b96e commitCommandVersion:0
   [junit4]   2> 455761 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5f06deee[collection1] main]
   [junit4]   2> 455761 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 455762 INFO  (searcherExecutor-2065-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5f06deee[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 455762 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 10
   [junit4]   2> 455763 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 455763 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 455763 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 455763 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*,test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 455763 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[xyz]} 0 0
   [junit4]   2> 455763 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 455763 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 455763 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 455764 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*,test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 455764 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 455764 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7099b96e commitCommandVersion:0
   [junit4]   2> 455787 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@168f9b15[collection1] main]
   [junit4]   2> 455788 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 455789 INFO  (searcherExecutor-2065-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@168f9b15[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c1)))}
   [junit4]   2> 455789 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 25
   [junit4]   2> 455789 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 455790 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 455790 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=test_nonstored_dv_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 455791 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*,test_nonstored_dv_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 455792 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[xyz]} 0 0
   [junit4]   2> 455792 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 455792 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7099b96e commitCommandVersion:0
   [junit4]   2> 455818 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@141af270[collection1] main]
   [junit4]   2> 455819 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 455820 INFO  (searcherExecutor-2065-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@141af270[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):c1)))}
   [junit4]   2> 455820 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 27
   [junit4]   2> 455820 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 455821 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 455821 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
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> 455821 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 455821 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7099b96e commitCommandVersion:0
   [junit4]   2> 455830 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@22218457[collection1] main]
   [junit4]   2> 455831 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 455832 INFO  (searcherExecutor-2065-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@22218457[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 455832 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 11
   [junit4]   2> 455832 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 455833 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=283542449
   [junit4]   2> 455833 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 455834 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 455836 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 455836 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 455836 INFO  (coreCloseExecutor-2069-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@6e5b6ad7
   [junit4]   2> 455838 INFO  (coreCloseExecutor-2069-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=1851484887
   [junit4]   2> 455841 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[41EB0B5C781BBEEC]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testOnEmptyIndex
   [junit4]   2> 455844 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued
   [junit4]   2> 455846 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 455855 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 455871 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 455874 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 455879 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 455879 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_41EB0B5C781BBEEC-001/tempDir-002/collection1/conf/managed-schema
   [junit4]   2> 455884 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_41EB0B5C781BBEEC-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_41EB0B5C781BBEEC-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 455900 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2cfd3e19
   [junit4]   2> 455902 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2cfd3e19
   [junit4]   2> 455902 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2cfd3e19
   [junit4]   2> 455908 INFO  (coreLoadExecutor-2076-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 455912 INFO  (coreLoadExecutor-2076-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 455916 INFO  (coreLoadExecutor-2076-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 455916 INFO  (coreLoadExecutor-2076-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_41EB0B5C781BBEEC-001/tempDir-002/collection1,
 trusted=true
   [junit4]   2> 455916 INFO  (coreLoadExecutor-2076-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@2cfd3e19
   [junit4]   2> 455916 INFO  (coreLoadExecutor-2076-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 455916 INFO  (coreLoadExecutor-2076-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_41EB0B5C781BBEEC-001/tempDir-002/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_41EB0B5C781BBEEC-001/init-core-data-001/]
   [junit4]   2> 455918 WARN  (coreLoadExecutor-2076-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> 455935 INFO  (coreLoadExecutor-2076-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 455935 INFO  (coreLoadExecutor-2076-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 455936 INFO  (coreLoadExecutor-2076-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=22, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=65.2919921875, 
floorSegmentMB=0.318359375, forceMergeDeletesPctAllowed=3.254896598883522, 
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7096697885185373
   [junit4]   2> 455936 INFO  (coreLoadExecutor-2076-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4abd695f[collection1] main]
   [junit4]   2> 455936 INFO  (coreLoadExecutor-2076-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_41EB0B5C781BBEEC-001/tempDir-002/collection1/conf
   [junit4]   2> 455937 INFO  (coreLoadExecutor-2076-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 455937 INFO  
(searcherExecutor-2077-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4abd695f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 455938 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 455938 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 455938 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 455939 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 0
   [junit4]   2> 455939 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 455939 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@77276ae4 commitCommandVersion:0
   [junit4]   2> 455962 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@83d2e0f[collection1] main]
   [junit4]   2> 455962 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 455963 INFO  (searcherExecutor-2077-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@83d2e0f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):c1)))}
   [junit4]   2> 455963 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 23
   [junit4]   2> 455963 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 455964 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 455965 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 455967 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 455968 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 455972 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 455973 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 0
   [junit4]   2> 455973 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 455973 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@77276ae4 commitCommandVersion:0
   [junit4]   2> 455999 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6a9078a4[collection1] main]
   [junit4]   2> 455999 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 456000 INFO  (searcherExecutor-2077-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6a9078a4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):c1)
 Uninverting(_3(8.0.0):c1)))}
   [junit4]   2> 456000 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 26
   [junit4]   2> 456000 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_i_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456001 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_i_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456001 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456001 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456001 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_i_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456001 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 456003 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 0
   [junit4]   2> 456003 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 456003 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@77276ae4 commitCommandVersion:0
   [junit4]   2> 456032 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@400cf721[collection1] main]
   [junit4]   2> 456032 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 456033 INFO  (searcherExecutor-2077-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@400cf721[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):c1)
 Uninverting(_3(8.0.0):c1) Uninverting(_4(8.0.0):c1)))}
   [junit4]   2> 456033 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 30
   [junit4]   2> 456034 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456035 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456036 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456037 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456038 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456039 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 456040 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[4]} 0 0
   [junit4]   2> 456041 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 456041 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@77276ae4 commitCommandVersion:0
   [junit4]   2> 456082 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@46006cb8[collection1] main]
   [junit4]   2> 456082 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 456084 INFO  (searcherExecutor-2077-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@46006cb8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):c1)
 Uninverting(_3(8.0.0):c1) Uninverting(_4(8.0.0):c1) 
Uninverting(_5(8.0.0):c1)))}
   [junit4]   2> 456084 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 43
   [junit4]   2> 456084 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_l_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456085 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_l_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456085 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456086 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456086 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_l_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456087 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 456089 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 0
   [junit4]   2> 456089 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 456089 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@77276ae4 commitCommandVersion:0
   [junit4]   2> 456147 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7fe11687[collection1] main]
   [junit4]   2> 456148 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 456149 INFO  (searcherExecutor-2077-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7fe11687[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):c1)
 Uninverting(_3(8.0.0):c1) Uninverting(_4(8.0.0):c1) Uninverting(_5(8.0.0):c1) 
Uninverting(_6(8.0.0):c1)))}
   [junit4]   2> 456149 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 59
   [junit4]   2> 456149 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_f_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456150 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_f_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456150 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456150 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456151 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_f_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456151 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 456166 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[6]} 0 0
   [junit4]   2> 456167 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 456167 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@77276ae4 commitCommandVersion:0
   [junit4]   2> 456222 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3f23508e[collection1] main]
   [junit4]   2> 456222 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 456223 INFO  (searcherExecutor-2077-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3f23508e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):c1)
 Uninverting(_3(8.0.0):c1) Uninverting(_4(8.0.0):c1) Uninverting(_5(8.0.0):c1) 
Uninverting(_6(8.0.0):c1) Uninverting(_7(8.0.0):c1)))}
   [junit4]   2> 456223 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 56
   [junit4]   2> 456224 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456225 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456225 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456226 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456226 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456227 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 456228 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[7]} 0 0
   [junit4]   2> 456229 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 456229 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@77276ae4 commitCommandVersion:0
   [junit4]   2> 456290 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@15ad8be3[collection1] main]
   [junit4]   2> 456290 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 456291 INFO  (searcherExecutor-2077-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@15ad8be3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):c1)
 Uninverting(_3(8.0.0):c1) Uninverting(_4(8.0.0):c1) Uninverting(_5(8.0.0):c1) 
Uninverting(_6(8.0.0):c1) Uninverting(_7(8.0.0):c1) Uninverting(_8(8.0.0):c1)))}
   [junit4]   2> 456291 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 62
   [junit4]   2> 456291 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_ss_dv&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456292 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_ss_dv,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456293 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456294 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456295 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_ss_dv,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456301 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 456304 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[8]} 0 0
   [junit4]   2> 456304 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 456304 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@77276ae4 commitCommandVersion:0
   [junit4]   2> 456365 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@33b369b1[collection1] main]
   [junit4]   2> 456365 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 456366 INFO  (searcherExecutor-2077-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@33b369b1[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):c1)
 Uninverting(_3(8.0.0):c1) Uninverting(_4(8.0.0):c1) Uninverting(_5(8.0.0):c1) 
Uninverting(_6(8.0.0):c1) Uninverting(_7(8.0.0):c1) Uninverting(_8(8.0.0):c1) 
Uninverting(_9(8.0.0):c1)))}
   [junit4]   2> 456366 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 62
   [junit4]   2> 456366 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_ss_dvo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456367 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_ss_dvo2,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456367 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456368 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456368 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_ss_dvo2,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456369 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
   [junit4]   2> 456370 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[9]} 0 0
   [junit4]   2> 456370 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 456370 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@77276ae4 commitCommandVersion:0
   [junit4]   2> 456418 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4f3d1fae[collection1] main]
   [junit4]   2> 456418 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 456419 INFO  (searcherExecutor-2077-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4f3d1fae[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):c1)
 Uninverting(_3(8.0.0):c1) Uninverting(_4(8.0.0):c1) Uninverting(_5(8.0.0):c1) 
Uninverting(_6(8.0.0):c1) Uninverting(_7(8.0.0):c1) Uninverting(_8(8.0.0):c1) 
Uninverting(_9(8.0.0):c1) Uninverting(_a(8.0.0):c1)))}
   [junit4]   2> 456419 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 49
   [junit4]   2> 456420 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456421 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456422 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456422 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456423 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456424 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=9 status=0 QTime=0
   [junit4]   2> 456425 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 456425 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456426 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[10]} 0 0
   [junit4]   2> 456426 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 456426 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@77276ae4 commitCommandVersion:0
   [junit4]   2> 456461 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@40c93794[collection1] main]
   [junit4]   2> 456463 INFO  (searcherExecutor-2077-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@40c93794[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(8.0.0):c1)))}
   [junit4]   2> 456464 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 456464 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 38
   [junit4]   2> 456465 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=test_s_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456475 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=test_s_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456475 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456476 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456476 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=test_s_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456485 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456486 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[11]} 0 0
   [junit4]   2> 456486 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 456486 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@77276ae4 commitCommandVersion:0
   [junit4]   2> 456552 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@ff5e5c[collection1] main]
   [junit4]   2> 456553 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 456556 INFO  (searcherExecutor-2077-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@ff5e5c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(8.0.0):c1)
 Uninverting(_c(8.0.0):c1)))}
   [junit4]   2> 456556 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 69
   [junit4]   2> 456557 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:11&fl=test_i_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456558 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:11&fl=test_i_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456559 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456560 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456561 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:11&fl=test_i_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456561 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 456563 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[12]} 0 1
   [junit4]   2> 456564 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 456564 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@77276ae4 commitCommandVersion:0
   [junit4]   2> 456612 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7520509a[collection1] main]
   [junit4]   2> 456612 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 456613 INFO  (searcherExecutor-2077-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7520509a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(8.0.0):c1)
 Uninverting(_c(8.0.0):c1) Uninverting(_d(8.0.0):c1)))}
   [junit4]   2> 456613 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 49
   [junit4]   2> 456614 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=test_ds_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456615 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=test_ds_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456616 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456617 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456618 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=test_ds_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456619 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 456620 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[13]} 0 0
   [junit4]   2> 456621 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 456621 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@77276ae4 commitCommandVersion:0
   [junit4]   2> 456656 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@632d3a9a[collection1] main]
   [junit4]   2> 456656 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 456657 INFO  (searcherExecutor-2077-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@632d3a9a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(8.0.0):c1)
 Uninverting(_c(8.0.0):c1) Uninverting(_d(8.0.0):c1) 
Uninverting(_e(8.0.0):c1)))}
   [junit4]   2> 456657 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 36
   [junit4]   2> 456657 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456658 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456659 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456659 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456660 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456661 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 456668 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[14]} 0 0
   [junit4]   2> 456668 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 456668 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@77276ae4 commitCommandVersion:0
   [junit4]   2> 456707 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2c3cbb6d[collection1] main]
   [junit4]   2> 456708 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 456709 INFO  (searcherExecutor-2077-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2c3cbb6d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(8.0.0):c1)
 Uninverting(_c(8.0.0):c1) Uninverting(_d(8.0.0):c1) Uninverting(_e(8.0.0):c1) 
Uninverting(_f(8.0.0):c1)))}
   [junit4]   2> 456709 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 40
   [junit4]   2> 456709 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456710 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456711 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456713 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456714 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456717 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 456723 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[15]} 0 1
   [junit4]   2> 456723 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 456723 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@77276ae4 commitCommandVersion:0
   [junit4]   2> 456765 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3a6eee3a[collection1] main]
   [junit4]   2> 456765 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 456766 INFO  (searcherExecutor-2077-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3a6eee3a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(8.0.0):c1)
 Uninverting(_c(8.0.0):c1) Uninverting(_d(8.0.0):c1) Uninverting(_e(8.0.0):c1) 
Uninverting(_f(8.0.0):c1) Uninverting(_g(8.0.0):c1)))}
   [junit4]   2> 456766 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 43
   [junit4]   2> 456767 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:15&fl=test_dts_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456768 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:15&fl=test_dts_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456768 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456769 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] 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> 456770 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:15&fl=test_dts_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456771 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 456773 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[16]} 0 0
   [junit4]   2> 456773 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 456773 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@77276ae4 commitCommandVersion:0
   [junit4]   2> 456815 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@70a5ffa2[collection1] main]
   [junit4]   2> 456815 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 456816 INFO  (searcherExecutor-2077-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@70a5ffa2[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(8.0.0):c1)
 Uninverting(_c(8.0.0):c1) Uninverting(_d(8.0.0):c1) Uninverting(_e(8.0.0):c1) 
Uninverting(_f(8.0.0):c1) Uninverting(_g(8.0.0):c1) Uninverting(_h(8.0.0):c1)))}
   [junit4]   2> 456816 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 43
   [junit4]   2> 456817 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:16&fl=test_s_dv&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456817 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:16&fl=test_s_dv,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456818 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:16&fl=*_dv&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456818 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:16&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456818 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:16&fl=test_s_dv,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456818 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 456819 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[17]} 0 0
   [junit4]   2> 456819 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 456819 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@77276ae4 commitCommandVersion:0
   [junit4]   2> 456865 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@16b480bb[collection1] main]
   [junit4]   2> 456867 INFO  (searcherExecutor-2077-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@16b480bb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(8.0.0):c1)
 Uninverting(_c(8.0.0):c1) Uninverting(_d(8.0.0):c1) Uninverting(_e(8.0.0):c1) 
Uninverting(_f(8.0.0):c1) Uninverting(_g(8.0.0):c1) Uninverting(_h(8.0.0):c1) 
Uninverting(_i(8.0.0):c1)))}
   [junit4]   2> 456867 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 456867 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 47
   [junit4]   2> 456867 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:17&fl=test_s_dvo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456868 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:17&fl=test_s_dvo2,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456868 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:17&fl=*vo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456868 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:17&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456869 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:17&fl=test_s_dvo2,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 456869 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
   [junit4]   2> 456872 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[18]} 0 0
   [junit4]   2> 456872 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 456872 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@77276ae4 commitCommandVersion:0
   [junit4]   2> 456918 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[41EB0B5C781BBEEC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5842f7e7[collection1] main]
   [junit4]   2> 456918 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMu

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

   [junit4]   2>        at 
org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stopMBeans(MetricsSourceAdapter.java:226)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stop(MetricsSourceAdapter.java:211)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stopSources(MetricsSystemImpl.java:463)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stop(MetricsSystemImpl.java:213)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.impl.MetricsSystemImpl.shutdown(MetricsSystemImpl.java:594)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdownInstance(DefaultMetricsSystem.java:72)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:171)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:872)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1726)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1705)
   [junit4]   2>        at 
org.apache.solr.cloud.hdfs.HdfsTestUtil.teardownClass(HdfsTestUtil.java:204)
   [junit4]   2>        at 
org.apache.solr.cloud.MoveReplicaHDFSTest.teardownClass(MoveReplicaHDFSTest.java:52)
   [junit4]   2>        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native 
Method)
   [junit4]   2>        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]   2>        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2>        at java.lang.reflect.Method.invoke(Method.java:498)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:870)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2>        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> Caused by: java.lang.NullPointerException
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:203)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3370)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:5729)
   [junit4]   2>        ... 54 more
   [junit4]   2> 1331391 INFO  
(SUITE-MoveReplicaHDFSTest-seed#[41EB0B5C781BBEEC]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:45237 45237
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.MoveReplicaHDFSTest_41EB0B5C781BBEEC-001
   [junit4]   2> Jul 05, 2017 3:12:57 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 67 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Lucene70, 
sim=RandomSimilarity(queryNorm=false): {}, locale=ar-IQ, timezone=Etc/UTC
   [junit4]   2> NOTE: Linux 4.10.0-21-generic amd64/Oracle Corporation 
1.8.0_131 (64-bit)/cpus=8,threads=2,free=353676680,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: [QueryEqualityTest, 
PathHierarchyTokenizerFactoryTest, BadIndexSchemaTest, TestSolrConfigHandler, 
ImplicitSnitchTest, NotRequiredUniqueKeyTest, TestOrdValues, 
AliasIntegrationTest, TestFieldCacheSort, UnloadDistributedZkTest, 
LeaderInitiatedRecoveryOnShardRestartTest, TestObjectReleaseTracker, 
SuggesterFSTTest, UniqFieldsUpdateProcessorFactoryTest, DocumentBuilderTest, 
SortSpecParsingTest, HdfsRecoverLeaseTest, ForceLeaderTest, 
ParsingFieldUpdateProcessorsTest, SolrGraphiteReporterTest, TestQueryUtils, 
TestFastWriter, TestDocTermOrds, TestFieldCacheWithThreads, 
TestSolrCloudWithSecureImpersonation, SuggesterTest, HdfsSyncSliceTest, 
HdfsBasicDistributedZk2Test, TestTolerantUpdateProcessorRandomCloud, 
ShowFileRequestHandlerTest, TestTrie, SolrInfoBeanTest, TestPseudoReturnFields, 
TemplateUpdateProcessorTest, SolrMetricReporterTest, 
CoreMergeIndexesAdminHandlerTest, TestUnifiedSolrHighlighter, 
TestJmxIntegration, TestPushWriter, BasicDistributedZk2Test, 
ReplicaListTransformerTest, TestSurroundQueryParser, SSLMigrationTest, 
SpatialRPTFieldTypeTest, SortByFunctionTest, URLClassifyProcessorTest, 
RequiredFieldsTest, DistributedQueueTest, TestComplexPhraseLeadingWildcard, 
ReturnFieldsTest, AnalysisErrorHandlingTest, UUIDUpdateProcessorFallbackTest, 
TestExactSharedStatsCache, DeleteInactiveReplicaTest, 
TestFoldingMultitermQuery, SolrTestCaseJ4Test, 
TestSha256AuthenticationProvider, TestSchemalessBufferedUpdates, 
NodeMutatorTest, TestSolrFieldCacheBean, TestSolrConfigHandlerConcurrent, 
RecoveryAfterSoftCommitTest, V2StandaloneTest, ClusterStateTest, 
TestSQLHandler, XsltUpdateRequestHandlerTest, TestUtils, TestConfigsApi, 
LeaderElectionTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, 
ExplicitHLLTest, ConnectionManagerTest, StatsReloadRaceTest, AutoCommitTest, 
CircularListTest, TestSolrDeletionPolicy2, TestTrackingShardHandlerFactory, 
TestExpandComponent, TestManagedSynonymFilterFactory, 
ClassificationUpdateProcessorFactoryTest, LeaderFailoverAfterPartitionTest, 
TestCloudJSONFacetJoinDomain, TestSolrCoreProperties, TestSolrQueryResponse, 
PeerSyncWithIndexFingerprintCachingTest, TestMaxScoreQueryParser, 
TestLegacyFieldReuse, TestInfoStreamLogging, TestTolerantSearch, 
TestCustomStream, SolrCloudReportersTest, TestAnalyzedSuggestions, 
CopyFieldTest, CollectionStateFormat2Test, TestBackupRepositoryFactory, 
TestDFRSimilarityFactory, DistributedSuggestComponentTest, 
PrimitiveFieldTypeTest, PreAnalyzedFieldTest, TestJsonFacetRefinement, 
TestLocalFSCloudBackupRestore, TestReload, 
VMParamsZkACLAndCredentialsProvidersTest, DateMathParserTest, 
TestSolrCoreSnapshots, TestDynamicFieldResource, ConfigureRecoveryStrategyTest, 
TestRandomFlRTGCloud, CleanupOldIndexTest, PingRequestHandlerTest, 
TestSubQueryTransformerCrossCore, BlockJoinFacetDistribTest, 
OverseerCollectionConfigSetProcessorTest, ZkSolrClientTest, TestZkChroot, 
ZkCLITest, ShardRoutingCustomTest, TestDistributedSearch, 
TermVectorComponentDistributedTest, TestStressReorder, TestRangeQuery, 
SimpleFacetsTest, StatsComponentTest, PeerSyncTest, DirectUpdateHandlerTest, 
TestIndexSearcher, HighlighterTest, DistributedQueryElevationComponentTest, 
SolrIndexSplitterTest, SignatureUpdateProcessorFactoryTest, 
SolrRequestParserTest, SuggesterTSTTest, TestStressLucene, SuggesterWFSTTest, 
TestCSVLoader, PolyFieldTest, SchemaVersionSpecificBehaviorTest, 
SolrCoreCheckLockOnStartupTest, TestUpdate, FieldMutatingUpdateProcessorTest, 
TestWordDelimiterFilterFactory, DistanceFunctionTest, CacheHeaderTest, 
LukeRequestHandlerTest, TestQueryTypes, TermsComponentTest, 
TermVectorComponentTest, IndexSchemaRuntimeFieldTest, LoggingHandlerTest, 
SolrPluginUtilsTest, UpdateRequestProcessorFactoryTest, QueryParsingTest, 
TestPartialUpdateDeduplication, SearchHandlerTest, TestQuerySenderListener, 
TestSolrIndexConfig, ResponseLogComponentTest, TestStressRecovery, 
MultiTermTest, SampleTest, NumericFieldsTest, MinimalSchemaTest, 
TestElisionMultitermQuery, ExternalFileFieldSortTest, 
TestPostingsSolrHighlighter, DirectSolrConnectionTest, TestPhraseSuggestions, 
SpellPossibilityIteratorTest, TestCharFilters, TestCodecSupport, 
SynonymTokenizerTest, TestLMDirichletSimilarityFactory, 
TestLMJelinekMercerSimilarityFactory, TestIBSimilarityFactory, 
TestBM25SimilarityFactory, TimeZoneUtilsTest, ResourceLoaderTest, 
ChaosMonkeyNothingIsSafeTest, CdcrReplicationHandlerTest, 
CloudExitableDirectoryReaderTest, CollectionsAPIAsyncDistributedZkTest, 
ConcurrentDeleteAndCreateCollectionTest, CreateCollectionCleanupTest, 
CustomCollectionTest, DistribCursorPagingTest, LeaderElectionContextKeyTest, 
LeaderInitiatedRecoveryOnCommitTest, MissingSegmentRecoveryTest, 
MoveReplicaHDFSTest]
   [junit4] Completed [510/726 (2!)] on J2 in 47.75s, 1 test, 1 error <<< 
FAILURES!

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

Reply via email to