Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/3813/
Java: 64bit/jdk-9-ea+173 -XX:-UseCompressedOops -XX:+UseSerialGC

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

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

Stack Trace:
java.lang.RuntimeException: mismatch: 'myid1'!='myid' @ response/docs/[0]/id
        at 
__randomizedtesting.SeedInfo.seed([1ACA33E0C04F56FA:28E0347E38B17223]:0)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:983)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:930)
        at 
org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults(TestUseDocValuesAsStored.java:243)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:564)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.base/java.lang.Thread.run(Thread.java:844)




Build Log:
[...truncated 13121 lines...]
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_1ACA33E0C04F56FA-001/init-core-data-001
   [junit4]   2> 1571673 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[1ACA33E0C04F56FA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 1571673 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[1ACA33E0C04F56FA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 1571674 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued
   [junit4]   2> 1571677 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1571681 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 1571685 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1571686 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1571690 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 1571691 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_1ACA33E0C04F56FA-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 1571708 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_1ACA33E0C04F56FA-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_1ACA33E0C04F56FA-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1571709 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1571726 ERROR (Finalizer) [    ] o.a.s.c.CoreContainer 
CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE 
RESOURCE LEAK!!!  instance=516919236
   [junit4]   2> 1571732 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2dcef138
   [junit4]   2> 1571734 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2dcef138
   [junit4]   2> 1571734 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2dcef138
   [junit4]   2> 1571740 INFO  (coreLoadExecutor-7120-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 1571746 INFO  (coreLoadExecutor-7120-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
   [junit4]   2> 1571751 INFO  (coreLoadExecutor-7120-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 1571751 INFO  (coreLoadExecutor-7120-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_1ACA33E0C04F56FA-001/tempDir-001/collection1,
 trusted=true
   [junit4]   2> 1571751 INFO  (coreLoadExecutor-7120-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 
'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2dcef138
   [junit4]   2> 1571752 INFO  (coreLoadExecutor-7120-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1571752 INFO  (coreLoadExecutor-7120-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_1ACA33E0C04F56FA-001/tempDir-001/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_1ACA33E0C04F56FA-001/init-core-data-001/]
   [junit4]   2> 1571754 INFO  (coreLoadExecutor-7120-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=27, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=18.1240234375, 
floorSegmentMB=1.099609375, forceMergeDeletesPctAllowed=2.5529360483804853, 
segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.576802297501779
   [junit4]   2> 1571765 WARN  (coreLoadExecutor-7120-thread-1) [    ] 
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> 1571792 INFO  (coreLoadExecutor-7120-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1571792 INFO  (coreLoadExecutor-7120-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1571793 INFO  (coreLoadExecutor-7120-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=12, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=19.125, 
floorSegmentMB=1.564453125, forceMergeDeletesPctAllowed=7.496428550069778, 
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1571793 INFO  (coreLoadExecutor-7120-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1c75165b[collection1] main]
   [junit4]   2> 1571794 INFO  (coreLoadExecutor-7120-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_1ACA33E0C04F56FA-001/tempDir-001/collection1/conf
   [junit4]   2> 1571794 INFO  (coreLoadExecutor-7120-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1571794 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1c75165b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1571795 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1571795 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1571795 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1571796 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 0
   [junit4]   2> 1571797 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1571797 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1571821 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@78b44fd[collection1] main]
   [junit4]   2> 1571821 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1571821 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@78b44fd[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):c1)))}
   [junit4]   2> 1571821 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 24
   [junit4]   2> 1571821 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_s_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1571822 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_s_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1571822 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1571823 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1571823 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_s_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1571823 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1571824 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 0
   [junit4]   2> 1571824 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1571824 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1571851 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@777aec60[collection1] main]
   [junit4]   2> 1571851 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@777aec60[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):c1)
 Uninverting(_1(6.7.0):c1)))}
   [junit4]   2> 1571851 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1571851 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 27
   [junit4]   2> 1571852 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1571852 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1571853 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1571853 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1571853 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1571854 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 1571855 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 0
   [junit4]   2> 1571855 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1571855 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1571896 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3ab592a8[collection1] main]
   [junit4]   2> 1571896 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1571896 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3ab592a8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):c1)
 Uninverting(_1(6.7.0):c1) Uninverting(_2(6.7.0):c1)))}
   [junit4]   2> 1571896 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 41
   [junit4]   2> 1571897 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_d_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1571897 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_d_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1571897 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1571898 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1571898 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_d_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1571898 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 1571899 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[4]} 0 0
   [junit4]   2> 1571899 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1571899 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1571933 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@696a74ab[collection1] main]
   [junit4]   2> 1571933 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1571934 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@696a74ab[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):c1)
 Uninverting(_1(6.7.0):c1) Uninverting(_2(6.7.0):c1) 
Uninverting(_3(6.7.0):c1)))}
   [junit4]   2> 1571934 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 34
   [junit4]   2> 1571934 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1571934 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1571935 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1571935 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1571935 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1571935 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 1571936 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 0
   [junit4]   2> 1571936 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1571936 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1571975 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7f8cab82[collection1] main]
   [junit4]   2> 1571976 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1571976 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7f8cab82[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):c1)
 Uninverting(_1(6.7.0):c1) Uninverting(_2(6.7.0):c1) Uninverting(_3(6.7.0):c1) 
Uninverting(_4(6.7.0):c1)))}
   [junit4]   2> 1571976 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 39
   [junit4]   2> 1571976 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1571977 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1571977 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1571977 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1571978 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1571978 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1571979 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[6]} 0 0
   [junit4]   2> 1571979 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1571979 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1572020 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@15aa552a[collection1] main]
   [junit4]   2> 1572021 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@15aa552a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):c1)
 Uninverting(_1(6.7.0):c1) Uninverting(_2(6.7.0):c1) Uninverting(_3(6.7.0):c1) 
Uninverting(_4(6.7.0):c1) Uninverting(_5(6.7.0):c1)))}
   [junit4]   2> 1572021 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1572021 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 41
   [junit4]   2> 1572021 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=test_dt_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572022 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=test_dt_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572022 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572022 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572023 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=test_dt_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572023 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 1572024 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[7]} 0 0
   [junit4]   2> 1572024 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1572024 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1572067 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@23fe2b1c[collection1] main]
   [junit4]   2> 1572068 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@23fe2b1c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):c1)
 Uninverting(_1(6.7.0):c1) Uninverting(_2(6.7.0):c1) Uninverting(_3(6.7.0):c1) 
Uninverting(_4(6.7.0):c1) Uninverting(_5(6.7.0):c1) Uninverting(_6(6.7.0):c1)))}
   [junit4]   2> 1572068 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1572068 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 44
   [junit4]   2> 1572068 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_s_dv&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572069 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_s_dv,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572069 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572069 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572070 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_s_dv,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572070 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 1572071 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[8]} 0 0
   [junit4]   2> 1572072 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1572072 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1572129 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@25aa3153[collection1] main]
   [junit4]   2> 1572130 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@25aa3153[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):c1)
 Uninverting(_1(6.7.0):c1) Uninverting(_2(6.7.0):c1) Uninverting(_3(6.7.0):c1) 
Uninverting(_4(6.7.0):c1) Uninverting(_5(6.7.0):c1) Uninverting(_6(6.7.0):c1) 
Uninverting(_7(6.7.0):c1)))}
   [junit4]   2> 1572130 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1572130 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 58
   [junit4]   2> 1572131 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572133 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572134 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572135 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572137 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572138 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
   [junit4]   2> 1572143 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[9]} 0 3
   [junit4]   2> 1572143 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1572143 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1572194 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@474eb279[collection1] main]
   [junit4]   2> 1572194 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1572195 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@474eb279[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):c1)
 Uninverting(_1(6.7.0):c1) Uninverting(_2(6.7.0):c1) Uninverting(_3(6.7.0):c1) 
Uninverting(_4(6.7.0):c1) Uninverting(_5(6.7.0):c1) Uninverting(_6(6.7.0):c1) 
Uninverting(_7(6.7.0):c1) Uninverting(_8(6.7.0):c1)))}
   [junit4]   2> 1572195 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 51
   [junit4]   2> 1572195 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=enum_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572196 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=enum_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572196 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572196 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572196 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=enum_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572197 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=9 status=0 QTime=0
   [junit4]   2> 1572197 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1572198 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572198 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[10]} 0 0
   [junit4]   2> 1572198 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1572198 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1572223 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@15c277b7[collection1] main]
   [junit4]   2> 1572223 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1572223 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@15c277b7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.7.0):c1)))}
   [junit4]   2> 1572224 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 26
   [junit4]   2> 1572224 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572225 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572225 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572225 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572225 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572226 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572226 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[11]} 0 0
   [junit4]   2> 1572226 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1572226 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1572254 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4cc829aa[collection1] main]
   [junit4]   2> 1572254 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1572254 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4cc829aa[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.7.0):c1)
 Uninverting(_a(6.7.0):c1)))}
   [junit4]   2> 1572254 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 28
   [junit4]   2> 1572255 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572255 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572255 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572255 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572256 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572256 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 1572256 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[12]} 0 0
   [junit4]   2> 1572256 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1572256 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1572286 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@40cbd7b3[collection1] main]
   [junit4]   2> 1572287 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1572287 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@40cbd7b3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.7.0):c1)
 Uninverting(_a(6.7.0):c1) Uninverting(_b(6.7.0):c1)))}
   [junit4]   2> 1572287 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 30
   [junit4]   2> 1572287 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=test_d_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572288 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=test_d_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572288 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572288 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572288 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=test_d_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572288 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 1572289 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[13]} 0 0
   [junit4]   2> 1572289 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1572289 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1572323 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4888076c[collection1] main]
   [junit4]   2> 1572324 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1572324 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4888076c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.7.0):c1)
 Uninverting(_a(6.7.0):c1) Uninverting(_b(6.7.0):c1) 
Uninverting(_c(6.7.0):c1)))}
   [junit4]   2> 1572324 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 34
   [junit4]   2> 1572324 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572333 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572334 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572335 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572335 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572336 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 1572337 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[14]} 0 0
   [junit4]   2> 1572337 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1572337 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1572373 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1fcb23ca[collection1] main]
   [junit4]   2> 1572373 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1572374 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1fcb23ca[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.7.0):c1)
 Uninverting(_a(6.7.0):c1) Uninverting(_b(6.7.0):c1) Uninverting(_c(6.7.0):c1) 
Uninverting(_d(6.7.0):c1)))}
   [junit4]   2> 1572374 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 36
   [junit4]   2> 1572374 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_f_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572374 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_f_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572375 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572375 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572375 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_f_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572375 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1572376 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[15]} 0 0
   [junit4]   2> 1572376 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1572376 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1572415 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@768766ef[collection1] main]
   [junit4]   2> 1572416 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1572416 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@768766ef[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.7.0):c1)
 Uninverting(_a(6.7.0):c1) Uninverting(_b(6.7.0):c1) Uninverting(_c(6.7.0):c1) 
Uninverting(_d(6.7.0):c1) Uninverting(_e(6.7.0):c1)))}
   [junit4]   2> 1572416 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 39
   [junit4]   2> 1572416 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572417 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572418 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572419 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572419 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572420 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 1572422 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[16]} 0 0
   [junit4]   2> 1572422 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1572422 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1572465 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@337752a7[collection1] main]
   [junit4]   2> 1572466 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1572466 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@337752a7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.7.0):c1)
 Uninverting(_a(6.7.0):c1) Uninverting(_b(6.7.0):c1) Uninverting(_c(6.7.0):c1) 
Uninverting(_d(6.7.0):c1) Uninverting(_e(6.7.0):c1) Uninverting(_f(6.7.0):c1)))}
   [junit4]   2> 1572466 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 44
   [junit4]   2> 1572466 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572467 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572467 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572467 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572467 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572468 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 1572468 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[17]} 0 0
   [junit4]   2> 1572469 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1572469 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1572517 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1810ce40[collection1] main]
   [junit4]   2> 1572518 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1572518 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1810ce40[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.7.0):c1)
 Uninverting(_a(6.7.0):c1) Uninverting(_b(6.7.0):c1) Uninverting(_c(6.7.0):c1) 
Uninverting(_d(6.7.0):c1) Uninverting(_e(6.7.0):c1) Uninverting(_f(6.7.0):c1) 
Uninverting(_g(6.7.0):c1)))}
   [junit4]   2> 1572518 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 49
   [junit4]   2> 1572518 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:17&fl=test_ss_dvo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572519 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:17&fl=test_ss_dvo2,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572520 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572520 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572521 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:17&fl=test_ss_dvo2,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572522 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
   [junit4]   2> 1572523 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[18]} 0 0
   [junit4]   2> 1572523 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1572523 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1572574 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4786d86[collection1] main]
   [junit4]   2> 1572574 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1572575 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4786d86[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.7.0):c1)
 Uninverting(_a(6.7.0):c1) Uninverting(_b(6.7.0):c1) Uninverting(_c(6.7.0):c1) 
Uninverting(_d(6.7.0):c1) Uninverting(_e(6.7.0):c1) Uninverting(_f(6.7.0):c1) 
Uninverting(_g(6.7.0):c1) Uninverting(_h(6.7.0):c1)))}
   [junit4]   2> 1572575 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 51
   [junit4]   2> 1572575 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:18&fl=enums_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572576 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:18&fl=enums_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572576 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:18&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572577 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:18&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572577 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:18&fl=enums_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572578 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=9 status=0 QTime=0
   [junit4]   2> 1572587 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1572587 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] 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> 1572588 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[19]} 0 0
   [junit4]   2> 1572588 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1572588 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1572612 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@563f1825[collection1] main]
   [junit4]   2> 1572612 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1572612 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@563f1825[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(6.7.0):c1)))}
   [junit4]   2> 1572613 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 25
   [junit4]   2> 1572613 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:19&fl=test_ss_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572614 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:19&fl=test_ss_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572615 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:19&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572616 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:19&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572617 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:19&fl=test_ss_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572617 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572619 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[20]} 0 0
   [junit4]   2> 1572619 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1572619 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1572646 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3a717ecd[collection1] main]
   [junit4]   2> 1572646 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1572647 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3a717ecd[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(6.7.0):c1)
 Uninverting(_j(6.7.0):c1)))}
   [junit4]   2> 1572647 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 27
   [junit4]   2> 1572647 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:20&fl=test_is_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572647 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:20&fl=test_is_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572648 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:20&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572648 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:20&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572648 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:20&fl=test_is_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572649 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 1572650 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[21]} 0 0
   [junit4]   2> 1572650 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1572650 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1572680 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@37b359b9[collection1] main]
   [junit4]   2> 1572680 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1572681 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@37b359b9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(6.7.0):c1)
 Uninverting(_j(6.7.0):c1) Uninverting(_k(6.7.0):c1)))}
   [junit4]   2> 1572681 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 30
   [junit4]   2> 1572681 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:21&fl=test_d_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572681 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:21&fl=test_d_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572681 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:21&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572682 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:21&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572682 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:21&fl=test_d_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572682 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 1572683 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[22]} 0 0
   [junit4]   2> 1572683 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1572683 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1572716 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@61f11230[collection1] main]
   [junit4]   2> 1572716 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1572716 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@61f11230[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(6.7.0):c1)
 Uninverting(_j(6.7.0):c1) Uninverting(_k(6.7.0):c1) 
Uninverting(_l(6.7.0):c1)))}
   [junit4]   2> 1572716 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 33
   [junit4]   2> 1572717 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:22&fl=test_l_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572717 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:22&fl=test_l_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572717 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:22&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572717 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:22&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572717 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:22&fl=test_l_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572718 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 1572718 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[23]} 0 0
   [junit4]   2> 1572718 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1572718 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1572755 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5dc36fd0[collection1] main]
   [junit4]   2> 1572755 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1572755 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5dc36fd0[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(6.7.0):c1)
 Uninverting(_j(6.7.0):c1) Uninverting(_k(6.7.0):c1) Uninverting(_l(6.7.0):c1) 
Uninverting(_m(6.7.0):c1)))}
   [junit4]   2> 1572755 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 37
   [junit4]   2> 1572756 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:23&fl=test_f_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572756 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:23&fl=test_f_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572757 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:23&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572757 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:23&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572758 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:23&fl=test_f_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572758 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1572759 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[24]} 0 0
   [junit4]   2> 1572760 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1572760 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1572803 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7d5a206d[collection1] main]
   [junit4]   2> 1572804 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1572804 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7d5a206d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(6.7.0):c1)
 Uninverting(_j(6.7.0):c1) Uninverting(_k(6.7.0):c1) Uninverting(_l(6.7.0):c1) 
Uninverting(_m(6.7.0):c1) Uninverting(_n(6.7.0):c1)))}
   [junit4]   2> 1572804 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 44
   [junit4]   2> 1572804 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:24&fl=test_dt_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572805 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:24&fl=test_dt_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572805 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:24&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572806 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:24&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572806 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:24&fl=test_dt_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1572806 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 1572807 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[25]} 0 0
   [junit4]   2> 1572807 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1572807 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ad48aa0
   [junit4]   2> 1572852 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6afae035[collection1] main]
   [junit4]   2> 1572852 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[1ACA33E0C04F56FA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1572852 INFO  (searcherExecutor-7121-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6afae035[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(6.7.0):c1)
 Uninverting(_j(6.7.0):c1) Uninverting(_k(6.7.0):c1) Uninverting(_l(6.7.0):c1) 
Uninverting(_m(6.7.0):c1) Uninverting(_n(6.7.0):c1) Uninverting(_o(6.7.0):c1)))}
   [junit4]   2> 1572852 INFO  (TEST-TestUseDocValuesAsStored.testRandomSin

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

pdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1586257 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[1ACA33E0C04F56FA]) [    
] 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> 1586257 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[1ACA33E0C04F56FA]) [    
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[myid1]} 0 0
   [junit4]   2> 1586257 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[1ACA33E0C04F56FA]) [    
] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1586257 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[1ACA33E0C04F56FA]) [    
] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@386de5e2
   [junit4]   2> 1586282 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[1ACA33E0C04F56FA]) [    
] o.a.s.s.SolrIndexSearcher Opening [Searcher@64ebbb83[collection1] main]
   [junit4]   2> 1586282 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[1ACA33E0C04F56FA]) [    
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1586282 INFO  (searcherExecutor-7181-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@64ebbb83[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7n(6.7.0):c1)))}
   [junit4]   2> 1586283 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[1ACA33E0C04F56FA]) [    
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 25
   [junit4]   2> 1586283 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[1ACA33E0C04F56FA]) [    
] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1586283 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[1ACA33E0C04F56FA]) [    
] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1586283 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[1ACA33E0C04F56FA]) [    
] 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> 1586283 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[1ACA33E0C04F56FA]) [    
] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1586283 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[1ACA33E0C04F56FA]) [    
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1162558378
   [junit4]   2> 1586284 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[1ACA33E0C04F56FA]) [    
] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1586284 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[1ACA33E0C04F56FA]) [    
] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 1586285 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[1ACA33E0C04F56FA]) [    
] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 1586286 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[1ACA33E0C04F56FA]) [    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1586286 INFO  (coreCloseExecutor-7185-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@53d00691
   [junit4]   2> 1586286 INFO  (coreCloseExecutor-7185-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 1586288 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[1ACA33E0C04F56FA]) [    
] o.a.s.SolrTestCaseJ4 ###Ending testManagedSchema
   [junit4]   2> 1586289 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[1ACA33E0C04F56FA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_1ACA33E0C04F56FA-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): {}, 
docValues:{}, maxPointsInLeafNode=1307, maxMBSortInHeap=5.651892896157541, 
sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=ksf-CM, 
timezone=Asia/Anadyr
   [junit4]   2> NOTE: Linux 4.10.0-21-generic amd64/Oracle Corporation 9-ea 
(64-bit)/cpus=8,threads=1,free=220762456,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: [AnalysisAfterCoreReloadTest, 
TestCrossCoreJoin, TestStressRecovery, TestReplicationHandler, 
TestSolr4Spatial, CacheHeaderTest, TestRemoteStreaming, 
DistributedQueryComponentCustomSortTest, TestCodecSupport, TestPathTrie, 
DataDrivenBlockJoinTest, TestCloudJSONFacetJoinDomain, 
TestPHPSerializedResponseWriter, BlobRepositoryCloudTest, 
RegexBoostProcessorTest, SolrCLIZkUtilsTest, TestJmxIntegration, 
TestGeoJSONResponseWriter, TestReplicationHandlerBackup, 
TestHighlightDedupGrouping, TestMacros, TestCryptoKeys, 
TolerantUpdateProcessorTest, AlternateDirectoryTest, TestInPlaceUpdatesDistrib, 
TestDownShardTolerantSearch, BasicAuthIntegrationTest, 
TestHalfAndHalfDocValues, ShufflingReplicaListTransformerTest, 
ReturnFieldsTest, TestBlendedInfixSuggestions, ResourceLoaderTest, 
TestSchemaResource, TestRandomFaceting, SSLMigrationTest, 
TestDocBasedVersionConstraints, TestCoreAdminApis, TestSolrCoreProperties, 
TestPushWriter, TestReplicaProperties, SecurityConfHandlerTest, EnumFieldTest, 
TlogReplayBufferedWhileIndexingTest, LeaderElectionIntegrationTest, 
TestSolrDeletionPolicy2, TestNumericTerms32, TestRangeQuery, 
TestQuerySenderNoQuery, SolrGangliaReporterTest, TestPseudoReturnFields, 
LargeFieldTest, HdfsRecoverLeaseTest, TestCustomStream, 
DistributedFacetPivotSmallAdvancedTest, InfoHandlerTest, 
TestAuthenticationFramework, TestSizeLimitedDistributedMap, 
SolrSlf4jReporterTest, TestStandardQParsers, UtilsToolTest, TestRealTimeGet, 
TestSha256AuthenticationProvider, TestComponentsName, 
TestBackupRepositoryFactory, SignatureUpdateProcessorFactoryTest, 
JavabinLoaderTest, BasicZkTest, TestMissingGroups, ZkNodePropsTest, 
ExitableDirectoryReaderTest, LeaderElectionContextKeyTest, TestOmitPositions, 
TestLocalFSCloudBackupRestore, TestCloudNestedDocsSort, SortSpecParsingTest, 
FullHLLTest, SharedFSAutoReplicaFailoverTest, TestFunctionQuery, 
TestIBSimilarityFactory, NumericFieldsTest, UUIDFieldTest, TestStressLiveNodes, 
SolrMetricManagerTest, AnalyticsMergeStrategyTest, TestFieldSortValues, 
JsonValidatorTest, SuggestComponentTest, TestSolrCloudWithSecureImpersonation, 
TestReRankQParserPlugin, TestPerFieldSimilarity, 
DefaultValueUpdateProcessorTest, RequiredFieldsTest, FileBasedSpellCheckerTest, 
BaseCdcrDistributedZkTest, TestNonDefinedSimilarityFactory, 
TestSerializedLuceneMatchVersion, ScriptEngineTest, VersionInfoTest, 
DocValuesNotIndexedTest, TestConfigSetsAPI, TestHashQParserPlugin, TestDocSet, 
HighlighterConfigTest, TestSchemalessBufferedUpdates, PeerSyncTest, 
TestManagedResourceStorage, TestLazyCores, TestReloadAndDeleteDocs, 
TestBlobHandler, TestDynamicFieldResource, NotRequiredUniqueKeyTest, 
TestSlowCompositeReaderWrapper, ShardRoutingTest, LeaderElectionTest, 
TestDistributedSearch, CurrencyFieldOpenExchangeTest, SpellCheckCollatorTest, 
SuggesterFSTTest, SolrRequestParserTest, TestFoldingMultitermQuery, 
TestStressLucene, TestCSVLoader, SchemaVersionSpecificBehaviorTest, 
TestWordDelimiterFilterFactory, StatelessScriptUpdateProcessorFactoryTest, 
DebugComponentTest, DisMaxRequestHandlerTest, StandardRequestHandlerTest, 
TestReversedWildcardFilterFactory, XmlUpdateRequestHandlerTest, 
TestValueSourceCache, MoreLikeThisHandlerTest, FastVectorHighlighterTest, 
MBeansHandlerTest, TestAnalyzedSuggestions, TestPartialUpdateDeduplication, 
CSVRequestHandlerTest, TestLFUCache, PreAnalyzedFieldTest, 
TestSystemIdResolver, PrimUtilsTest, DateFieldTest, DOMUtilTest, 
RAMDirectoryFactoryTest, ClusterStateTest, TestSolrJ, TestLRUCache, 
TestDocumentBuilder, SliceStateTest, FileUtilsTest, 
DistributedMLTComponentTest, CursorPagingTest, DistributedIntervalFacetingTest, 
CdcrReplicationHandlerTest, CdcrRequestHandlerTest, CdcrVersionReplicationTest, 
CollectionTooManyReplicasTest, ConcurrentDeleteAndCreateCollectionTest, 
ConfigSetsAPITest, CustomCollectionTest, DeleteLastCustomShardedReplicaTest, 
DeleteReplicaTest, DistribJoinFromCollectionTest, ForceLeaderTest, 
MultiThreadedOCPTest, ReplicationFactorTest, RollingRestartTest, 
TestCloudRecovery, TestSSLRandomization, TestShortCircuitedRequests, 
TestSolrCloudWithDelegationTokens, TestSolrCloudWithKerberosAlt, 
TestStressInPlaceUpdates, HdfsSyncSliceTest, 
HdfsTlogReplayBufferedWhileIndexingTest, HdfsUnloadDistributedZkTest, 
ZkStateReaderTest, RulesTest, TestInfoStreamLogging, TestInitParams, 
TestNRTOpen, TestSolrDynamicMBean, TestSolrCloudSnapshots, 
TestSystemCollAutoCreate, DistributedFacetPivotSmallTest, 
DistributedQueryComponentOptimizationTest, TestUnifiedSolrHighlighter, 
WrapperMergePolicyFactoryTest, JvmMetricsTest, SolrCoreMetricManagerTest, 
SolrMetricsIntegrationTest, SolrGraphiteReporterTest, 
SubstringBytesRefFilterTest, JSONWriterTest, TestCustomDocTransformer, 
TestRawTransformer, TestSolrQueryResponse, TestSubQueryTransformerCrossCore, 
TestDynamicFieldCollectionResource, TestSchemaNameResource, 
TestSolrQueryParserDefaultOperatorResource, TestManagedStopFilterFactory, 
DocValuesMissingTest, PreAnalyzedFieldManagedSchemaCloudTest, 
TestBulkSchemaConcurrent, TestManagedSchemaThreadSafety, 
TestUseDocValuesAsStored]
   [junit4] Completed [648/715 (1!)] on J0 in 14.63s, 6 tests, 1 error <<< 
FAILURES!

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

Reply via email to