Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/3228/
Java: 64bit/jdk1.8.0_121 -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([4E521F655782AD0E:7C7818FBAF7C89D7]:0)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:969)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:916)
        at 
org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults(TestUseDocValuesAsStored.java:243)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 13087 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/J1/temp/solr.schema.TestUseDocValuesAsStored_4E521F655782AD0E-001/init-core-data-001
   [junit4]   2> 1670605 WARN  
(SUITE-TestUseDocValuesAsStored-seed#[4E521F655782AD0E]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=12 numCloses=12
   [junit4]   2> 1670605 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[4E521F655782AD0E]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 1670606 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[4E521F655782AD0E]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 1670609 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued
   [junit4]   2> 1670610 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1670617 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1670623 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1670625 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1670631 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 1670632 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_4E521F655782AD0E-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 1670654 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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/J1/temp/solr.schema.TestUseDocValuesAsStored_4E521F655782AD0E-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_4E521F655782AD0E-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1670655 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1670680 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 1670684 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3834ad89
   [junit4]   2> 1670685 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 1670691 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3834ad89
   [junit4]   2> 1670691 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 1670692 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3834ad89
   [junit4]   2> 1670707 INFO  (coreLoadExecutor-8040-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1670740 INFO  (coreLoadExecutor-8040-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 1670750 INFO  (coreLoadExecutor-8040-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 1670751 INFO  (coreLoadExecutor-8040-thread-1) [    
x:collection1] 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/J1/temp/solr.schema.TestUseDocValuesAsStored_4E521F655782AD0E-001/tempDir-001/collection1,
 trusted=true
   [junit4]   2> 1670751 WARN  (coreLoadExecutor-8040-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 1670751 INFO  (coreLoadExecutor-8040-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3834ad89
   [junit4]   2> 1670751 INFO  (coreLoadExecutor-8040-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1670751 INFO  (coreLoadExecutor-8040-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_4E521F655782AD0E-001/tempDir-001/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_4E521F655782AD0E-001/init-core-data-001/]
   [junit4]   2> 1670754 INFO  (coreLoadExecutor-8040-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=27, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=37.55859375, 
floorSegmentMB=1.5927734375, forceMergeDeletesPctAllowed=26.544855107795435, 
segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1670798 WARN  (coreLoadExecutor-8040-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler 
{type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, registerPath=/solr,/v2, 
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 1670834 INFO  (coreLoadExecutor-8040-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1670834 INFO  (coreLoadExecutor-8040-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1670835 INFO  (coreLoadExecutor-8040-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=20, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=56.55859375, 
floorSegmentMB=0.564453125, forceMergeDeletesPctAllowed=4.906852795236507, 
segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1670835 INFO  (coreLoadExecutor-8040-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@ecc66e7[collection1] 
main]
   [junit4]   2> 1670836 INFO  (coreLoadExecutor-8040-thread-1) [    
x:collection1] 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/J1/temp/solr.schema.TestUseDocValuesAsStored_4E521F655782AD0E-001/tempDir-001/collection1/conf
   [junit4]   2> 1670836 INFO  (coreLoadExecutor-8040-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1670836 INFO  
(searcherExecutor-8041-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@ecc66e7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1670837 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1670837 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1670837 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1670838 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 0
   [junit4]   2> 1670841 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1670841 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1670897 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@58bc8710[collection1] main]
   [junit4]   2> 1670897 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1670897 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@58bc8710[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1)))}
   [junit4]   2> 1670897 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 55
   [junit4]   2> 1670898 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_ss_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1670899 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_ss_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1670900 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1670904 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1670906 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_ss_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1670907 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1670909 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 0
   [junit4]   2> 1670910 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1670910 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1671038 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5dc7735b[collection1] main]
   [junit4]   2> 1671038 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1671038 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5dc7735b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1)
 Uninverting(_1(6.6.0):c1)))}
   [junit4]   2> 1671038 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 128
   [junit4]   2> 1671039 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_is_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1671040 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_is_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1671040 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671041 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671042 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_is_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1671042 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 1671044 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 0
   [junit4]   2> 1671044 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1671044 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1671111 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@709cf855[collection1] main]
   [junit4]   2> 1671111 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1671111 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@709cf855[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1)
 Uninverting(_1(6.6.0):c1) Uninverting(_2(6.6.0):c1)))}
   [junit4]   2> 1671111 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 67
   [junit4]   2> 1671112 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671112 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671113 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671113 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671113 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671113 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 1671114 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[4]} 0 0
   [junit4]   2> 1671115 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1671115 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1671151 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@18579a88[collection1] main]
   [junit4]   2> 1671151 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1671151 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@18579a88[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1)
 Uninverting(_1(6.6.0):c1) Uninverting(_2(6.6.0):c1) 
Uninverting(_3(6.6.0):c1)))}
   [junit4]   2> 1671151 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 36
   [junit4]   2> 1671152 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671152 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671153 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671154 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671154 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671154 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 1671155 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 0
   [junit4]   2> 1671156 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1671156 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1671207 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7c6b299c[collection1] main]
   [junit4]   2> 1671208 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1671208 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7c6b299c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1)
 Uninverting(_1(6.6.0):c1) Uninverting(_2(6.6.0):c1) Uninverting(_3(6.6.0):c1) 
Uninverting(_4(6.6.0):c1)))}
   [junit4]   2> 1671208 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 52
   [junit4]   2> 1671208 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_fs_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1671209 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_fs_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1671209 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671210 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671211 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_fs_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1671211 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1671213 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[6]} 0 0
   [junit4]   2> 1671213 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1671213 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1671274 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ee20307[collection1] main]
   [junit4]   2> 1671274 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1671275 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1ee20307[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1)
 Uninverting(_1(6.6.0):c1) Uninverting(_2(6.6.0):c1) Uninverting(_3(6.6.0):c1) 
Uninverting(_4(6.6.0):c1) Uninverting(_5(6.6.0):c1)))}
   [junit4]   2> 1671275 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 61
   [junit4]   2> 1671275 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=test_dts_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1671276 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=test_dts_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1671277 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671277 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671277 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=test_dts_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1671278 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 1671279 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[7]} 0 0
   [junit4]   2> 1671279 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1671280 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1671383 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7b0923bb[collection1] main]
   [junit4]   2> 1671384 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1671384 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7b0923bb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1)
 Uninverting(_1(6.6.0):c1) Uninverting(_2(6.6.0):c1) Uninverting(_3(6.6.0):c1) 
Uninverting(_4(6.6.0):c1) Uninverting(_5(6.6.0):c1) Uninverting(_6(6.6.0):c1)))}
   [junit4]   2> 1671384 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 104
   [junit4]   2> 1671386 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_ss_dv&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1671387 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_ss_dv,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1671393 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=*_dv&wt=xml} hits=1 status=0 QTime=5
   [junit4]   2> 1671395 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671396 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_ss_dv,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1671397 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 1671400 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[8]} 0 0
   [junit4]   2> 1671400 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1671400 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1671464 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@582e266f[collection1] main]
   [junit4]   2> 1671465 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1671465 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@582e266f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1)
 Uninverting(_1(6.6.0):c1) Uninverting(_2(6.6.0):c1) Uninverting(_3(6.6.0):c1) 
Uninverting(_4(6.6.0):c1) Uninverting(_5(6.6.0):c1) Uninverting(_6(6.6.0):c1) 
Uninverting(_7(6.6.0):c1)))}
   [junit4]   2> 1671465 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 64
   [junit4]   2> 1671466 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_s_dvo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1671466 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_s_dvo2,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1671467 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671467 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671468 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_s_dvo2,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1671468 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
   [junit4]   2> 1671470 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[9]} 0 0
   [junit4]   2> 1671470 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1671470 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1671515 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@54c8fb23[collection1] main]
   [junit4]   2> 1671516 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1671516 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@54c8fb23[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1)
 Uninverting(_1(6.6.0):c1) Uninverting(_2(6.6.0):c1) Uninverting(_3(6.6.0):c1) 
Uninverting(_4(6.6.0):c1) Uninverting(_5(6.6.0):c1) Uninverting(_6(6.6.0):c1) 
Uninverting(_7(6.6.0):c1) Uninverting(_8(6.6.0):c1)))}
   [junit4]   2> 1671516 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 45
   [junit4]   2> 1671516 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671517 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671517 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671518 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671518 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671518 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=9 status=0 QTime=0
   [junit4]   2> 1671519 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1671519 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671520 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[10]} 0 0
   [junit4]   2> 1671520 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1671520 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1671602 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@38afcd28[collection1] main]
   [junit4]   2> 1671603 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1671603 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@38afcd28[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.6.0):c1)))}
   [junit4]   2> 1671603 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 83
   [junit4]   2> 1671604 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671604 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671605 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671605 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671605 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671617 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1671617 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[11]} 0 0
   [junit4]   2> 1671618 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1671618 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1671688 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@28c9dbf0[collection1] main]
   [junit4]   2> 1671688 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1671688 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@28c9dbf0[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.6.0):c1)
 Uninverting(_a(6.6.0):c1)))}
   [junit4]   2> 1671688 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 70
   [junit4]   2> 1671689 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:11&fl=test_is_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1671690 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:11&fl=test_is_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1671691 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671692 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671693 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:11&fl=test_is_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1671694 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 1671696 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[12]} 0 0
   [junit4]   2> 1671696 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1671696 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1671775 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3659c3ce[collection1] main]
   [junit4]   2> 1671775 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1671775 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3659c3ce[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.6.0):c1)
 Uninverting(_a(6.6.0):c1) Uninverting(_b(6.6.0):c1)))}
   [junit4]   2> 1671776 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 79
   [junit4]   2> 1671776 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671777 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671778 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671778 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671779 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671779 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 1671780 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[13]} 0 0
   [junit4]   2> 1671780 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1671780 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1671886 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@78334564[collection1] main]
   [junit4]   2> 1671887 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1671887 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@78334564[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.6.0):c1)
 Uninverting(_a(6.6.0):c1) Uninverting(_b(6.6.0):c1) 
Uninverting(_c(6.6.0):c1)))}
   [junit4]   2> 1671887 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 106
   [junit4]   2> 1671888 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671889 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671890 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671891 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671892 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671892 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 1671894 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[14]} 0 0
   [junit4]   2> 1671894 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1671894 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1671946 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2ee5ad7d[collection1] main]
   [junit4]   2> 1671947 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1671947 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2ee5ad7d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.6.0):c1)
 Uninverting(_a(6.6.0):c1) Uninverting(_b(6.6.0):c1) Uninverting(_c(6.6.0):c1) 
Uninverting(_d(6.6.0):c1)))}
   [junit4]   2> 1671947 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 52
   [junit4]   2> 1671947 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671948 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671948 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671949 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671949 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1671950 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1671951 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[15]} 0 0
   [junit4]   2> 1671951 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1671951 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1672037 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1f8357db[collection1] main]
   [junit4]   2> 1672038 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1672038 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1f8357db[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.6.0):c1)
 Uninverting(_a(6.6.0):c1) Uninverting(_b(6.6.0):c1) Uninverting(_c(6.6.0):c1) 
Uninverting(_d(6.6.0):c1) Uninverting(_e(6.6.0):c1)))}
   [junit4]   2> 1672038 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 86
   [junit4]   2> 1672038 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672039 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672039 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672039 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672040 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672040 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 1672041 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[16]} 0 0
   [junit4]   2> 1672042 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1672042 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1672103 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@62604bee[collection1] main]
   [junit4]   2> 1672104 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1672104 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@62604bee[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.6.0):c1)
 Uninverting(_a(6.6.0):c1) Uninverting(_b(6.6.0):c1) Uninverting(_c(6.6.0):c1) 
Uninverting(_d(6.6.0):c1) Uninverting(_e(6.6.0):c1) Uninverting(_f(6.6.0):c1)))}
   [junit4]   2> 1672104 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 62
   [junit4]   2> 1672105 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672105 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672105 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672106 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672106 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672107 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 1672108 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[17]} 0 0
   [junit4]   2> 1672108 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1672108 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1672162 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@55a0cec3[collection1] main]
   [junit4]   2> 1672162 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1672162 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@55a0cec3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.6.0):c1)
 Uninverting(_a(6.6.0):c1) Uninverting(_b(6.6.0):c1) Uninverting(_c(6.6.0):c1) 
Uninverting(_d(6.6.0):c1) Uninverting(_e(6.6.0):c1) Uninverting(_f(6.6.0):c1) 
Uninverting(_g(6.6.0):c1)))}
   [junit4]   2> 1672162 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 54
   [junit4]   2> 1672163 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:17&fl=test_s_dvo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1672163 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:17&fl=test_s_dvo2,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1672164 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672164 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672165 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:17&fl=test_s_dvo2,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1672165 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
   [junit4]   2> 1672166 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[18]} 0 0
   [junit4]   2> 1672166 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1672166 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1672214 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6a0a3562[collection1] main]
   [junit4]   2> 1672215 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1672215 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6a0a3562[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.6.0):c1)
 Uninverting(_a(6.6.0):c1) Uninverting(_b(6.6.0):c1) Uninverting(_c(6.6.0):c1) 
Uninverting(_d(6.6.0):c1) Uninverting(_e(6.6.0):c1) Uninverting(_f(6.6.0):c1) 
Uninverting(_g(6.6.0):c1) Uninverting(_h(6.6.0):c1)))}
   [junit4]   2> 1672215 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 48
   [junit4]   2> 1672215 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672216 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672217 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672218 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672219 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672219 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=9 status=0 QTime=0
   [junit4]   2> 1672221 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1672221 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672222 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[19]} 0 0
   [junit4]   2> 1672222 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1672222 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1672250 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2fc2b642[collection1] main]
   [junit4]   2> 1672250 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1672250 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2fc2b642[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(6.6.0):c1)))}
   [junit4]   2> 1672252 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 29
   [junit4]   2> 1672252 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672253 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672253 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672254 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672254 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672255 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1672255 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[20]} 0 0
   [junit4]   2> 1672256 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1672256 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1672309 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2527d140[collection1] main]
   [junit4]   2> 1672309 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1672309 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2527d140[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(6.6.0):c1)
 Uninverting(_j(6.6.0):c1)))}
   [junit4]   2> 1672310 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 54
   [junit4]   2> 1672310 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:20&fl=test_i_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1672310 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:20&fl=test_i_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1672311 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672311 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672311 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:20&fl=test_i_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1672312 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 1672313 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[21]} 0 0
   [junit4]   2> 1672313 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1672313 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1672384 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5c86e2a9[collection1] main]
   [junit4]   2> 1672384 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1672385 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5c86e2a9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(6.6.0):c1)
 Uninverting(_j(6.6.0):c1) Uninverting(_k(6.6.0):c1)))}
   [junit4]   2> 1672385 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 71
   [junit4]   2> 1672385 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672386 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672386 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672386 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672386 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672387 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 1672387 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[22]} 0 0
   [junit4]   2> 1672388 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1672388 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1672472 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@65e15a5b[collection1] main]
   [junit4]   2> 1672472 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1672472 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@65e15a5b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(6.6.0):c1)
 Uninverting(_j(6.6.0):c1) Uninverting(_k(6.6.0):c1) 
Uninverting(_l(6.6.0):c1)))}
   [junit4]   2> 1672473 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 85
   [junit4]   2> 1672473 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672474 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672474 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672474 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672475 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672475 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 1672476 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[23]} 0 0
   [junit4]   2> 1672476 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1672476 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1672560 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@39a491d5[collection1] main]
   [junit4]   2> 1672561 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1672561 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@39a491d5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(6.6.0):c1)
 Uninverting(_j(6.6.0):c1) Uninverting(_k(6.6.0):c1) Uninverting(_l(6.6.0):c1) 
Uninverting(_m(6.6.0):c1)))}
   [junit4]   2> 1672561 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 84
   [junit4]   2> 1672561 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672561 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672562 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672562 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672562 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672563 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1672563 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[24]} 0 0
   [junit4]   2> 1672564 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1672564 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@29224e0e
   [junit4]   2> 1672622 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@13fe7bf5[collection1] main]
   [junit4]   2> 1672622 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1672622 INFO  (searcherExecutor-8041-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@13fe7bf5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(6.6.0):c1)
 Uninverting(_j(6.6.0):c1) Uninverting(_k(6.6.0):c1) Uninverting(_l(6.6.0):c1) 
Uninverting(_m(6.6.0):c1) Uninverting(_n(6.6.0):c1)))}
   [junit4]   2> 1672622 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 58
   [junit4]   2> 1672623 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672623 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672623 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672624 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672624 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] 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> 1672624 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 1672625 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[25]} 0 0
   [junit4]   2> 1672625 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1672625 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[4E521F655782AD0E])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitDat

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

1697458 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[4E521F655782AD0E]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[4]} 0 0
   [junit4]   2> 1697459 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[4E521F655782AD0E]) 
[    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1697459 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[4E521F655782AD0E]) 
[    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@60e4d63c
   [junit4]   2> 1697523 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[4E521F655782AD0E]) 
[    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5d97bc90[collection1] main]
   [junit4]   2> 1697524 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[4E521F655782AD0E]) 
[    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1697524 INFO  (searcherExecutor-8101-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5d97bc90[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7n(6.6.0):c4)))}
   [junit4]   2> 1697524 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[4E521F655782AD0E]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 65
   [junit4]   2> 1697525 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[4E521F655782AD0E]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_mvt_dvt_st_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1697525 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[4E521F655782AD0E]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_mvt_dvt_sf_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1697525 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[4E521F655782AD0E]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_mvt_dvf_st_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1697526 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[4E521F655782AD0E]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_mvt_dvu_st_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1697526 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[4E521F655782AD0E]) 
[    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1697526 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[4E521F655782AD0E]) 
[    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2091235192
   [junit4]   2> 1697526 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[4E521F655782AD0E]) 
[    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1697526 INFO  (coreCloseExecutor-8105-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@51a14fc1
   [junit4]   2> 1697527 INFO  (coreCloseExecutor-8105-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 1697530 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[4E521F655782AD0E]) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1697530 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[4E521F655782AD0E]) 
[    ] o.a.s.SolrTestCaseJ4 ###Ending testMultivaluedOrdering
   [junit4]   2> 1697531 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[4E521F655782AD0E]-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/J1/temp/solr.schema.TestUseDocValuesAsStored_4E521F655782AD0E-001
   [junit4]   2> NOTE: test params are: codec=CheapBastard, 
sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=is, 
timezone=Africa/Bissau
   [junit4]   2> NOTE: Linux 4.4.0-66-generic amd64/Oracle Corporation 
1.8.0_121 (64-bit)/cpus=12,threads=1,free=75848264,total=514969600
   [junit4]   2> NOTE: All tests run in this JVM: [TestSQLHandler, 
FileUtilsTest, DateMathParserTest, SimplePostToolTest, TestTrie, 
TestStressUserVersions, TestPartialUpdateDeduplication, TestIntervalFaceting, 
TestMinMaxOnMultiValuedField, PeerSyncWithIndexFingerprintCachingTest, 
ZkCLITest, TestHashPartitioner, DistributedIntervalFacetingTest, 
ConvertedLegacyTest, DistributedTermsComponentTest, TestRawResponseWriter, 
TestHdfsCloudBackupRestore, TestUpdate, DateRangeFieldTest, 
TestCollapseQParserPlugin, HdfsDirectoryTest, TestRTimerTree, 
TestCorePropertiesReload, TestSolrIndexConfig, 
TestSha256AuthenticationProvider, TestCSVResponseWriter, SmileWriterTest, 
OutOfBoxZkACLAndCredentialsProvidersTest, TestShortCircuitedRequests, 
RankQueryTest, TestUseDocValuesAsStored2, TestDistribDocBasedVersion, 
TestFileDictionaryLookup, ZkControllerTest, DocumentBuilderTest, 
TestRecoveryHdfs, HdfsRestartWhileUpdatingTest, TestGraphMLResponseWriter, 
DistributedFacetPivotSmallAdvancedTest, TestCloudSchemaless, 
ConnectionManagerTest, TestWordDelimiterFilterFactory, SpellCheckCollatorTest, 
HdfsNNFailoverTest, ShardRoutingTest, BJQParserTest, AnalysisErrorHandlingTest, 
DocExpirationUpdateProcessorFactoryTest, CollectionsAPIDistributedZkTest, 
TestLuceneMatchVersion, TestQuerySenderListener, TestAuthorizationFramework, 
MoreLikeThisHandlerTest, TestNumericTerms64, DistributedMLTComponentTest, 
BasicFunctionalityTest, TestQueryUtils, TestDistributedGrouping, 
TestStressInPlaceUpdates, TestQueryTypes, TestImplicitCoreProperties, 
TestEmbeddedSolrServerConstructors, ZkSolrClientTest, 
TestSolrConfigHandlerConcurrent, SimpleMLTQParserTest, 
SpellingQueryConverterTest, ClassificationUpdateProcessorIntegrationTest, 
CollectionsAPISolrJTest, ChaosMonkeySafeLeaderTest, CSVRequestHandlerTest, 
RAMDirectoryFactoryTest, MultiTermTest, IndexSchemaTest, 
TestElisionMultitermQuery, VMParamsZkACLAndCredentialsProvidersTest, 
TestCustomSort, BasicAuthIntegrationTest, TestNonDefinedSimilarityFactory, 
BadCopyFieldTest, ConnectionReuseTest, SharedFSAutoReplicaFailoverUtilsTest, 
SortByFunctionTest, TestApiFramework, DistributedQueueTest, 
RemoteQueryErrorTest, DirectSolrConnectionTest, AssignTest, 
CleanupOldIndexTest, SoftAutoCommitTest, DirectoryFactoryTest, 
TestManagedSchemaAPI, IndexBasedSpellCheckerTest, EchoParamsTest, 
DeleteInactiveReplicaTest, DistributedQueryComponentCustomSortTest, 
TestSweetSpotSimilarityFactory, DistanceFunctionTest, 
TestSuggestSpellingConverter, JsonLoaderTest, SolrIndexConfigTest, 
SearchHandlerTest, URLClassifyProcessorTest, PreAnalyzedUpdateProcessorTest, 
TestSolrCloudSnapshots, TermVectorComponentTest, TestRTGBase, 
TestXmlQParserPlugin, SolrCloudExampleTest, TestBulkSchemaConcurrent, 
TestFiltering, TestConfigSetImmutable, TestSurroundQueryParser, 
OverseerModifyCollectionTest, TestInPlaceUpdatesStandalone, LoggingHandlerTest, 
DeleteReplicaTest, TestMiniSolrCloudCluster, TestSimpleQParserPlugin, 
SyncSliceTest, OverseerTest, TestReplicationHandler, ShardRoutingCustomTest, 
TermVectorComponentDistributedTest, TestRealTimeGet, TestJoin, SolrCoreTest, 
TestGroupingSearch, TestFunctionQuery, TestBadConfig, TestIndexSearcher, 
CurrencyFieldOpenExchangeTest, DistributedQueryElevationComponentTest, 
CoreAdminHandlerTest, SuggesterTSTTest, SpatialFilterTest, NoCacheHeaderTest, 
WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, 
TestPseudoReturnFields, QueryEqualityTest, DirectUpdateHandlerOptimizeTest, 
XsltUpdateRequestHandlerTest, CacheHeaderTest, StandardRequestHandlerTest, 
TestWriterPerf, DocumentAnalysisRequestHandlerTest, PrimitiveFieldTypeTest, 
XmlUpdateRequestHandlerTest, PathHierarchyTokenizerFactoryTest, 
RequiredFieldsTest, UpdateRequestProcessorFactoryTest, QueryParsingTest, 
UniqFieldsUpdateProcessorFactoryTest, TestComponentsName, 
TestBinaryResponseWriter, TestQuerySenderNoQuery, CopyFieldTest, 
ResponseLogComponentTest, BadComponentTest, TestStressRecovery, 
NumericFieldsTest, TestFuzzyAnalyzedSuggestions, TestPhraseSuggestions, 
TestIBSimilarityFactory, TestJmxMonitoredMap, TestFastOutputStream, 
PluginInfoTest, PreAnalyzedFieldTest, TestSystemIdResolver, PrimUtilsTest, 
DOMUtilTest, TestSolrJ, TestLRUCache, TestDocumentBuilder, SliceStateTest, 
SystemInfoHandlerTest, UUIDFieldTest, CircularListTest, CursorPagingTest, 
TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort, 
TestSimpleTrackingShardHandler, AsyncCallRequestStatusResponseTest, 
CdcrRequestHandlerTest, CollectionReloadTest, 
ConcurrentDeleteAndCreateCollectionTest, CreateCollectionCleanupTest, 
DeleteShardTest, DistribCursorPagingTest, LeaderElectionContextKeyTest, 
LeaderFailoverAfterPartitionTest, NodeMutatorTest, 
OverriddenZkACLAndCredentialsProvidersTest, OverseerTaskQueueTest, 
SimpleCollectionCreateDeleteTest, TestCloudDeleteByQuery, TestCloudInspectUtil, 
TestCloudPivotFacet, TestConfigSetsAPIExclusivity, 
TestDeleteCollectionOnDownNodes, TestLeaderInitiatedRecoveryThread, 
TestLockTree, TestMiniSolrCloudClusterSSL, TestRequestForwarding, 
TestRequestStatusCollectionAPI, TestSolrCloudWithSecureImpersonation, 
TestTolerantUpdateProcessorRandomCloud, BlobRepositoryCloudTest, TestNRTOpen, 
TestSolrConfigHandler, TestSolrCoreSnapshots, TestConfigReload, 
DistributedFacetPivotLongTailTest, FacetPivotSmallTest, 
ShufflingReplicaListTransformerTest, SpatialHeatmapFacetsTest, 
TestFieldTypeCollectionResource, TestSchemaNameResource, 
TestSerializedLuceneMatchVersion, TestUniqueKeyFieldResource, 
TestManagedSynonymFilterFactory, PreAnalyzedFieldManagedSchemaCloudTest, 
TestUseDocValuesAsStored]
   [junit4] Completed [644/703 (1!)] on J1 in 26.94s, 6 tests, 1 error <<< 
FAILURES!

[...truncated 43577 lines...]

---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to