Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/3235/
Java: 32bit/jdk1.8.0_121 -client -XX:+UseParallelGC

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([34A412C10041D8D9:68E155FF8BFFC00]: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 11306 lines...]
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/init-core-data-001
   [junit4]   2> 158402 WARN  
(SUITE-TestUseDocValuesAsStored-seed#[34A412C10041D8D9]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=767 numCloses=767
   [junit4]   2> 158403 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[34A412C10041D8D9]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 158404 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[34A412C10041D8D9]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 158409 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testUseDocValuesAsStoredFalse
   [junit4]   2> 158411 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 158432 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 158446 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] 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> 158477 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 158491 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 158518 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 158585 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 158590 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 158623 WARN  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 158628 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8
   [junit4]   2> 158628 WARN  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 158634 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8
   [junit4]   2> 158634 WARN  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 158634 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8
   [junit4]   2> 158651 INFO  (coreLoadExecutor-782-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 158669 INFO  (coreLoadExecutor-782-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 158685 INFO  (coreLoadExecutor-782-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 158685 INFO  (coreLoadExecutor-782-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/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-001/collection1,
 trusted=true
   [junit4]   2> 158686 WARN  (coreLoadExecutor-782-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 158686 INFO  (coreLoadExecutor-782-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8
   [junit4]   2> 158686 INFO  (coreLoadExecutor-782-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 158686 INFO  (coreLoadExecutor-782-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/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-001/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/init-core-data-001/]
   [junit4]   2> 158689 INFO  (coreLoadExecutor-782-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=50, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=57.7744140625, 
floorSegmentMB=2.1494140625, forceMergeDeletesPctAllowed=18.124101851191075, 
segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 158807 WARN  (coreLoadExecutor-782-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> 158855 INFO  (coreLoadExecutor-782-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 158855 INFO  (coreLoadExecutor-782-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 158857 INFO  (coreLoadExecutor-782-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=22, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 158858 INFO  (coreLoadExecutor-782-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@17ab395[collection1] 
main]
   [junit4]   2> 158858 INFO  (coreLoadExecutor-782-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/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-001/collection1/conf
   [junit4]   2> 158858 INFO  (coreLoadExecutor-782-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 158859 INFO  
(searcherExecutor-783-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@17ab395[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 158860 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 158861 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid]} 0 1
   [junit4]   2> 158865 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 158865 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1bcef23
   [junit4]   2> 159239 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c2edfd[collection1] main]
   [junit4]   2> 159240 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 159240 INFO  (searcherExecutor-783-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1c2edfd[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C1)))}
   [junit4]   2> 159240 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 375
   [junit4]   2> 159242 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 159243 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 159243 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid&fl=id,nonstored_dv_*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 159243 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid&fl=id,nonstored_dv_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 159245 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 159245 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=14108827
   [junit4]   2> 159245 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 159245 INFO  (coreCloseExecutor-787-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@acb937
   [junit4]   2> 159255 INFO  (coreCloseExecutor-787-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 159262 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 159263 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testUseDocValuesAsStoredFalse
   [junit4]   2> 159266 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testMultipleSearchResults
   [junit4]   2> 159268 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 159284 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 159296 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] 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> 159302 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 159314 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 159314 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-002/collection1/conf/managed-schema
   [junit4]   2> 159376 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 159378 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 159406 WARN  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 159410 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8
   [junit4]   2> 159410 WARN  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 159416 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8
   [junit4]   2> 159416 WARN  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 159416 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8
   [junit4]   2> 159438 INFO  (coreLoadExecutor-794-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 159457 INFO  (coreLoadExecutor-794-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 159473 INFO  (coreLoadExecutor-794-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 159473 INFO  (coreLoadExecutor-794-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/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-002/collection1,
 trusted=true
   [junit4]   2> 159474 WARN  (coreLoadExecutor-794-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 159474 INFO  (coreLoadExecutor-794-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8
   [junit4]   2> 159474 INFO  (coreLoadExecutor-794-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 159475 INFO  (coreLoadExecutor-794-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/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-002/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/init-core-data-001/]
   [junit4]   2> 159479 WARN  (coreLoadExecutor-794-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> 159531 INFO  (coreLoadExecutor-794-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 159531 INFO  (coreLoadExecutor-794-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 159533 INFO  (coreLoadExecutor-794-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=50, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=57.7744140625, 
floorSegmentMB=2.1494140625, forceMergeDeletesPctAllowed=18.124101851191075, 
segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 159536 INFO  (coreLoadExecutor-794-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1f884c[collection1] 
main]
   [junit4]   2> 159537 INFO  (coreLoadExecutor-794-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/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-002/collection1/conf
   [junit4]   2> 159537 INFO  (coreLoadExecutor-794-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 159537 INFO  
(searcherExecutor-795-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1f884c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C1)))}
   [junit4]   2> 159539 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 159560 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid1]} 0 2
   [junit4]   2> 159562 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid2]} 0 0
   [junit4]   2> 159564 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid3]} 0 0
   [junit4]   2> 159565 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid4]} 0 0
   [junit4]   2> 159566 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid5]} 0 0
   [junit4]   2> 159567 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid6]} 0 0
   [junit4]   2> 159568 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 159568 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5f0625
   [junit4]   2> 160089 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ab36dd[collection1] main]
   [junit4]   2> 160090 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 160090 INFO  (searcherExecutor-795-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1ab36dd[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C1)
 Uninverting(_1(6.6.0):C6)))}
   [junit4]   2> 160090 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 523
   [junit4]   2> 160099 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid*&fl=*&wt=xml} hits=7 status=0 QTime=8
   [junit4]   2> 160101 ERROR 
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.SolrTestCaseJ4 query failed JSON validation. error=mismatch: 
'myid1'!='myid' @ response/docs/[0]/id
   [junit4]   2>  expected 
=/response/docs==[{'id':'myid1','test_is_dvo':[101,102,103]},{'id':'myid2','test_is_dvo':[201,202]},{'id':'myid3','test_is_dvo':[301,302,303,304]},{'id':'myid4','test_s_dvo':'hello','test_is_dvo':[401,402]},{'id':'myid5'},{'id':'myid6','test_s_dvo':'hello'}]
   [junit4]   2>  response = {
   [junit4]   2>   "responseHeader":{
   [junit4]   2>     "status":0,
   [junit4]   2>     "QTime":8},
   [junit4]   2>   "response":{"numFound":7,"start":0,"docs":[
   [junit4]   2>       {
   [junit4]   2>         "id":"myid"},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid1",
   [junit4]   2>         "test_is_dvo":[101,
   [junit4]   2>           102,
   [junit4]   2>           103]},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid2",
   [junit4]   2>         "test_is_dvo":[201,
   [junit4]   2>           202]},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid3",
   [junit4]   2>         "test_is_dvo":[301,
   [junit4]   2>           302,
   [junit4]   2>           303,
   [junit4]   2>           304]},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid4",
   [junit4]   2>         "test_s_dvo":"hello",
   [junit4]   2>         "test_is_dvo":[401,
   [junit4]   2>           402]},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid5"},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid6",
   [junit4]   2>         "test_s_dvo":"hello"}]
   [junit4]   2>   }}
   [junit4]   2> 
   [junit4]   2>  request = q=id:myid*&fl=*&wt=xml
   [junit4]   2> 160101 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 160101 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=27103897
   [junit4]   2> 160101 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 160102 INFO  (coreCloseExecutor-799-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@18f1391
   [junit4]   2> 160106 INFO  (coreCloseExecutor-799-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 160109 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 160110 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testMultipleSearchResults
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestUseDocValuesAsStored -Dtests.method=testMultipleSearchResults 
-Dtests.seed=34A412C10041D8D9 -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=ar-SY -Dtests.timezone=Europe/Copenhagen -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.87s J0 | 
TestUseDocValuesAsStored.testMultipleSearchResults <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: mismatch: 
'myid1'!='myid' @ response/docs/[0]/id
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([34A412C10041D8D9:68E155FF8BFFC00]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:969)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:916)
   [junit4]    >        at 
org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults(TestUseDocValuesAsStored.java:243)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 160136 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued
   [junit4]   2> 160139 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 160158 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 160177 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 160185 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 160198 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 160198 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-003/collection1/conf/managed-schema
   [junit4]   2> 160263 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 160265 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 160291 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 160297 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8
   [junit4]   2> 160297 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 160304 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8
   [junit4]   2> 160304 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 160304 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8
   [junit4]   2> 160325 INFO  (coreLoadExecutor-806-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 160341 INFO  (coreLoadExecutor-806-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 160353 INFO  (coreLoadExecutor-806-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 160354 INFO  (coreLoadExecutor-806-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/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-003/collection1,
 trusted=true
   [junit4]   2> 160354 WARN  (coreLoadExecutor-806-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 160354 INFO  (coreLoadExecutor-806-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8
   [junit4]   2> 160354 INFO  (coreLoadExecutor-806-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 160354 INFO  (coreLoadExecutor-806-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/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-003/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/init-core-data-001/]
   [junit4]   2> 160360 WARN  (coreLoadExecutor-806-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> 160424 INFO  (coreLoadExecutor-806-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 160425 INFO  (coreLoadExecutor-806-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 160426 INFO  (coreLoadExecutor-806-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=50, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=57.7744140625, 
floorSegmentMB=2.1494140625, forceMergeDeletesPctAllowed=18.124101851191075, 
segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 160430 INFO  (coreLoadExecutor-806-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@12c445d[collection1] 
main]
   [junit4]   2> 160431 INFO  (coreLoadExecutor-806-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/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-003/collection1/conf
   [junit4]   2> 160431 INFO  (coreLoadExecutor-806-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 160431 INFO  
(searcherExecutor-807-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@12c445d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C1)
 Uninverting(_1(6.6.0):C6)))}
   [junit4]   2> 160432 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 160441 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 160442 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 160443 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 0
   [junit4]   2> 160444 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 160444 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b09f96
   [junit4]   2> 160942 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@dfef29[collection1] main]
   [junit4]   2> 160943 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 160943 INFO  (searcherExecutor-807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@dfef29[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):C1)))}
   [junit4]   2> 160945 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 501
   [junit4]   2> 160946 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_s_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 160948 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_s_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 160949 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 160953 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 160954 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_s_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 160955 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 160957 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 0
   [junit4]   2> 160958 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 160958 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b09f96
   [junit4]   2> 161442 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@174a3b5[collection1] main]
   [junit4]   2> 161443 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 161444 INFO  (searcherExecutor-807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@174a3b5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):C1)
 Uninverting(_3(6.6.0):C1)))}
   [junit4]   2> 161444 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 486
   [junit4]   2> 161446 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_i_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 161447 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_i_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 161449 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 161450 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 161452 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_i_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 161453 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 161456 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 1
   [junit4]   2> 161457 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 161466 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b09f96
   [junit4]   2> 161869 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@510c02[collection1] main]
   [junit4]   2> 161870 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 161870 INFO  (searcherExecutor-807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@510c02[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):C1)
 Uninverting(_3(6.6.0):C1) Uninverting(_4(6.6.0):C1)))}
   [junit4]   2> 161870 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 412
   [junit4]   2> 161871 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 161873 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 161874 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 161875 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 161877 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 161878 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 161880 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[4]} 0 1
   [junit4]   2> 161880 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 161880 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b09f96
   [junit4]   2> 162114 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@18049fc[collection1] main]
   [junit4]   2> 162114 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 162114 INFO  (searcherExecutor-807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@18049fc[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(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> 162115 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 234
   [junit4]   2> 162117 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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=1
   [junit4]   2> 162118 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 162119 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 162120 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 162122 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 162123 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 162125 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 0
   [junit4]   2> 162126 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 162126 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b09f96
   [junit4]   2> 162367 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@fa00f3[collection1] main]
   [junit4]   2> 162369 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 162369 INFO  (searcherExecutor-807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@fa00f3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(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> 162369 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 243
   [junit4]   2> 162371 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_f_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 162373 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_f_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 162375 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 162377 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 162379 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_f_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 162380 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 162383 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[6]} 0 1
   [junit4]   2> 162384 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 162384 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b09f96
   [junit4]   2> 163346 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@14765fe[collection1] main]
   [junit4]   2> 163347 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 163347 INFO  (searcherExecutor-807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@14765fe[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(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> 163347 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 962
   [junit4]   2> 163349 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=test_dt_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 163350 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=test_dt_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 163352 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=*dvo&wt=xml} hits=1 status=0 QTime=1
   [junit4]   2> 163353 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 163355 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=test_dt_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 163356 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 163359 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[7]} 0 1
   [junit4]   2> 163359 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 163359 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b09f96
   [junit4]   2> 163865 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a09157[collection1] main]
   [junit4]   2> 163866 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 163866 INFO  (searcherExecutor-807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1a09157[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(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> 163866 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 506
   [junit4]   2> 163867 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_s_dv&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 163869 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_s_dv,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 163870 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=*_dv&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 163871 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 163873 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_s_dv,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 163873 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 163876 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[8]} 0 1
   [junit4]   2> 163877 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 163877 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b09f96
   [junit4]   2> 164301 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@e8b16d[collection1] main]
   [junit4]   2> 164302 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 164302 INFO  (searcherExecutor-807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@e8b16d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(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) 
Uninverting(_9(6.6.0):C1)))}
   [junit4]   2> 164302 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 425
   [junit4]   2> 164303 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_ss_dvo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 164306 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_ss_dvo2,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 164308 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 164310 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 164313 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_ss_dvo2,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 164315 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
   [junit4]   2> 164318 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[9]} 0 1
   [junit4]   2> 164319 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 164319 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b09f96
   [junit4]   2> 164635 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2cbb9[collection1] main]
   [junit4]   2> 164636 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 164636 INFO  (searcherExecutor-807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2cbb9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(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) 
Uninverting(_9(6.6.0):C1) Uninverting(_a(6.6.0):C1)))}
   [junit4]   2> 164636 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 317
   [junit4]   2> 164638 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 164639 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 164640 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 164641 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 164642 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 164643 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=9 status=0 QTime=0
   [junit4]   2> 164645 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 164645 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 164646 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[10]} 0 0
   [junit4]   2> 164647 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 164651 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b09f96
   [junit4]   2> 165009 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@264f5a[collection1] main]
   [junit4]   2> 165010 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 165010 INFO  (searcherExecutor-807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@264f5a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.6.0):C1)))}
   [junit4]   2> 165014 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 367
   [junit4]   2> 165015 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=test_ss_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 165017 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=test_ss_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 165018 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 165020 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 165022 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=test_ss_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 165023 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 165026 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[11]} 0 1
   [junit4]   2> 165027 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 165027 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b09f96
   [junit4]   2> 165286 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1bb7a06[collection1] main]
   [junit4]   2> 165287 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 165287 INFO  (searcherExecutor-807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1bb7a06[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.6.0):C1)
 Uninverting(_c(6.6.0):C1)))}
   [junit4]   2> 165287 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 260
   [junit4]   2> 165288 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 165290 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 165292 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 165294 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 165295 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 165297 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 165300 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[12]} 0 1
   [junit4]   2> 165300 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 165300 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b09f96
   [junit4]   2> 165474 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@e5df21[collection1] main]
   [junit4]   2> 165475 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 165475 INFO  (searcherExecutor-807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@e5df21[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.6.0):C1)
 Uninverting(_c(6.6.0):C1) Uninverting(_d(6.6.0):C1)))}
   [junit4]   2> 165476 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 175
   [junit4]   2> 165477 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 165479 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 165480 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 165481 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 165482 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 165484 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 165486 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[13]} 0 1
   [junit4]   2> 165487 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 165487 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b09f96
   [junit4]   2> 165710 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@13d5b20[collection1] main]
   [junit4]   2> 165711 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 165711 INFO  (searcherExecutor-807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@13d5b20[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(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> 165711 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 224
   [junit4]   2> 165713 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 165715 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 165717 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 165719 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 165721 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 165723 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 165726 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[14]} 0 1
   [junit4]   2> 165727 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 165727 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b09f96
   [junit4]   2> 165954 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ba2032[collection1] main]
   [junit4]   2> 165955 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 165955 INFO  (searcherExecutor-807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1ba2032[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(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> 165955 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 228
   [junit4]   2> 165956 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_fs_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 165959 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_fs_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 165960 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 165962 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 165964 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_fs_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 165966 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 165971 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[15]} 0 1
   [junit4]   2> 165972 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 165972 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b09f96
   [junit4]   2> 166210 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@159b106[collection1] main]
   [junit4]   2> 166211 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 166211 INFO  (searcherExecutor-807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@159b106[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(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> 166211 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 239
   [junit4]   2> 166213 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 166215 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 166216 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 166218 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 166221 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 166222 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 166226 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[16]} 0 0
   [junit4]   2> 166226 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 166226 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b09f96
   [junit4]   2> 166432 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5167ee[collection1] main]
   [junit4]   2> 166433 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 166433 INFO  (searcherExecutor-807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5167ee[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(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> 166433 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 207
   [junit4]   2> 166434 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 166435 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 166437 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 166437 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 166438 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] 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> 166439 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 166441 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[17]} 0 0
   [junit4]   2> 166442 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes

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

ma name=test-non-stored-docvalues
   [junit4]   2> 252539 INFO  (coreLoadExecutor-842-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 252539 INFO  (coreLoadExecutor-842-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/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-006/collection1,
 trusted=true
   [junit4]   2> 252540 WARN  (coreLoadExecutor-842-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 252540 INFO  (coreLoadExecutor-842-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8
   [junit4]   2> 252540 INFO  (coreLoadExecutor-842-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 252540 INFO  (coreLoadExecutor-842-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/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-006/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/init-core-data-001/]
   [junit4]   2> 252545 WARN  (coreLoadExecutor-842-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> 252607 INFO  (coreLoadExecutor-842-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 252607 INFO  (coreLoadExecutor-842-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 252609 INFO  (coreLoadExecutor-842-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=50, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=57.7744140625, 
floorSegmentMB=2.1494140625, forceMergeDeletesPctAllowed=18.124101851191075, 
segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 252611 INFO  (coreLoadExecutor-842-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@ef61c1[collection1] 
main]
   [junit4]   2> 252612 INFO  (coreLoadExecutor-842-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/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-006/collection1/conf
   [junit4]   2> 252612 INFO  (coreLoadExecutor-842-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 252612 INFO  
(searcherExecutor-843-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@ef61c1[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7m(6.6.0):C1)))}
   [junit4]   2> 252614 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) 
[    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 252614 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) 
[    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 252615 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) 
[    ] 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> 252616 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[1]} 0 1
   [junit4]   2> 252617 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[2]} 0 0
   [junit4]   2> 252618 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[3]} 0 0
   [junit4]   2> 252619 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[4]} 0 0
   [junit4]   2> 252620 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) 
[    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 252620 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) 
[    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@c5b523
   [junit4]   2> 252931 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) 
[    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@deb391[collection1] main]
   [junit4]   2> 252932 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) 
[    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 252932 INFO  (searcherExecutor-843-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@deb391[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7n(6.6.0):C4)))}
   [junit4]   2> 252933 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 312
   [junit4]   2> 252934 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) 
[    ] 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> 252934 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) 
[    ] 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> 252935 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) 
[    ] 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> 252935 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) 
[    ] 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> 252935 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) 
[    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 252936 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) 
[    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=16909966
   [junit4]   2> 252936 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) 
[    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 252936 INFO  (coreCloseExecutor-847-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@3e1afb
   [junit4]   2> 252937 INFO  (coreCloseExecutor-847-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 252941 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 252943 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) 
[    ] o.a.s.SolrTestCaseJ4 ###Ending testMultivaluedOrdering
   [junit4]   2> 252946 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[34A412C10041D8D9]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001
   [junit4]   2> NOTE: test params are: 
codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION,
 chunkSize=1021, maxDocsPerChunk=653, blockSize=506), 
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION,
 chunkSize=1021, blockSize=506)), 
sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=ar-SY, 
timezone=Europe/Copenhagen
   [junit4]   2> NOTE: Linux 4.4.0-66-generic i386/Oracle Corporation 1.8.0_121 
(32-bit)/cpus=12,threads=1,free=135219280,total=336330752
   [junit4]   2> NOTE: All tests run in this JVM: [TestDynamicLoading, 
TestLockTree, TestRemoteStreaming, TestManagedResourceStorage, 
TestLRUStatsCache, TestAtomicUpdateErrorCases, TestInitQParser, 
RequiredFieldsTest, TestDocBasedVersionConstraints, 
TestSlowCompositeReaderWrapper, TestSearcherReuse, DocValuesNotIndexedTest, 
CdcrReplicationHandlerTest, ClassificationUpdateProcessorFactoryTest, 
TestDynamicFieldCollectionResource, HighlighterConfigTest, 
UUIDUpdateProcessorFallbackTest, BasicDistributedZkTest, 
TestUseDocValuesAsStored]
   [junit4] Completed [89/703 (1!)] on J0 in 94.59s, 6 tests, 1 error <<< 
FAILURES!

[...truncated 41376 lines...]

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

Reply via email to