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

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([68B87FEE659909DA:5A9278709D672D03]:0)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:983)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:930)
        at 
org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults(TestUseDocValuesAsStored.java:243)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:564)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.base/java.lang.Thread.run(Thread.java:844)




Build Log:
[...truncated 13104 lines...]
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4]   2> Creating dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001\init-core-data-001
   [junit4]   2> 2490092 WARN  
(SUITE-TestUseDocValuesAsStored-seed#[68B87FEE659909DA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=32 numCloses=32
   [junit4]   2> 2490092 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[68B87FEE659909DA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 2490092 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[68B87FEE659909DA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 2490092 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testUseDocValuesAsStoredFalse
   [junit4]   2> 2490133 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 2490141 WARN  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, 
use <mergePolicyFactory> instead.
   [junit4]   2> 2490142 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 2490143 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] 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> 2490143 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 2490157 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 2490159 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001\tempDir-001\collection1\conf\managed-schema
   [junit4]   2> 2490161 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001\tempDir-001\collection1\conf\schema-non-stored-docvalues.xml
 to 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001\tempDir-001\collection1\conf\schema-non-stored-docvalues.xml.bak
   [junit4]   2> 2490165 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 2490207 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@382549d3
   [junit4]   2> 2490215 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@382549d3
   [junit4]   2> 2490216 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@382549d3
   [junit4]   2> 2490229 WARN  (coreLoadExecutor-11370-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 2490229 INFO  (coreLoadExecutor-11370-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 2490237 INFO  (coreLoadExecutor-11370-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
   [junit4]   2> 2490247 INFO  (coreLoadExecutor-11370-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 2490247 INFO  (coreLoadExecutor-11370-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001\tempDir-001\collection1,
 trusted=true
   [junit4]   2> 2490247 INFO  (coreLoadExecutor-11370-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 
'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@382549d3
   [junit4]   2> 2490247 INFO  (coreLoadExecutor-11370-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2490247 INFO  (coreLoadExecutor-11370-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001\tempDir-001\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001\init-core-data-001\]
   [junit4]   2> 2490254 INFO  (coreLoadExecutor-11370-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=26, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=22.2724609375, 
floorSegmentMB=0.609375, forceMergeDeletesPctAllowed=9.071143353586825, 
segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 2490263 WARN  (coreLoadExecutor-11370-thread-1) [    ] 
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = 
requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, registerPath=/solr,/v2, 
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 2490352 INFO  (coreLoadExecutor-11370-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2490353 INFO  (coreLoadExecutor-11370-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2490354 INFO  (coreLoadExecutor-11370-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=34, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=19.23828125, 
floorSegmentMB=1.5888671875, forceMergeDeletesPctAllowed=8.721248613557883, 
segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 2490357 INFO  (coreLoadExecutor-11370-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3d71e602[collection1] main]
   [junit4]   2> 2490358 INFO  (coreLoadExecutor-11370-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001\tempDir-001\collection1\conf
   [junit4]   2> 2490358 INFO  (coreLoadExecutor-11370-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2490359 INFO  (searcherExecutor-11371-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3d71e602[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2490360 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 2490361 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid]} 0 0
   [junit4]   2> 2490368 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2490368 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@31bb1f16
   [junit4]   2> 2490392 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@780c94db[collection1] main]
   [junit4]   2> 2490393 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2490393 INFO  (searcherExecutor-11371-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@780c94db[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):c1)))}
   [junit4]   2> 2490394 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 25
   [junit4]   2> 2490394 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] 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> 2490395 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] 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> 2490395 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] 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> 2490395 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] 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> 2490396 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 2490396 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] 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> 2490396 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 2490396 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1094618216
   [junit4]   2> 2490396 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 2490398 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 2490399 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 2490400 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 2490400 INFO  (coreCloseExecutor-11375-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@470b21d5
   [junit4]   2> 2490402 INFO  (coreCloseExecutor-11375-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 2490406 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[68B87FEE659909DA])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testUseDocValuesAsStoredFalse
   [junit4]   2> 2490408 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testMultipleSearchResults
   [junit4]   2> 2490422 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 2490430 WARN  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, 
use <mergePolicyFactory> instead.
   [junit4]   2> 2490431 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 2490437 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] 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> 2490440 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 2490448 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 2490449 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001\tempDir-002\collection1\conf\managed-schema
   [junit4]   2> 2490452 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001\tempDir-002\collection1\conf\schema-non-stored-docvalues.xml
 to 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001\tempDir-002\collection1\conf\schema-non-stored-docvalues.xml.bak
   [junit4]   2> 2490454 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 2490503 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@382549d3
   [junit4]   2> 2490509 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@382549d3
   [junit4]   2> 2490509 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@382549d3
   [junit4]   2> 2490523 WARN  (coreLoadExecutor-11382-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 2490523 INFO  (coreLoadExecutor-11382-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 2490548 INFO  (coreLoadExecutor-11382-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
   [junit4]   2> 2490556 INFO  (coreLoadExecutor-11382-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 2490556 INFO  (coreLoadExecutor-11382-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001\tempDir-002\collection1,
 trusted=true
   [junit4]   2> 2490557 INFO  (coreLoadExecutor-11382-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 
'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@382549d3
   [junit4]   2> 2490557 INFO  (coreLoadExecutor-11382-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2490557 INFO  (coreLoadExecutor-11382-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001\tempDir-002\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001\init-core-data-001\]
   [junit4]   2> 2490564 WARN  (coreLoadExecutor-11382-thread-1) [    ] 
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = 
requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, registerPath=/solr,/v2, 
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 2490664 INFO  (coreLoadExecutor-11382-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2490664 INFO  (coreLoadExecutor-11382-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2490667 INFO  (coreLoadExecutor-11382-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=26, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=22.2724609375, 
floorSegmentMB=0.609375, forceMergeDeletesPctAllowed=9.071143353586825, 
segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 2490676 INFO  (coreLoadExecutor-11382-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7468770a[collection1] main]
   [junit4]   2> 2490676 INFO  (coreLoadExecutor-11382-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001\tempDir-002\collection1\conf
   [junit4]   2> 2490677 INFO  (coreLoadExecutor-11382-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2490678 INFO  (searcherExecutor-11383-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7468770a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):c1)))}
   [junit4]   2> 2490680 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 2490681 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid1]} 0 0
   [junit4]   2> 2490682 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid2]} 0 0
   [junit4]   2> 2490682 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid3]} 0 0
   [junit4]   2> 2490683 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid4]} 0 0
   [junit4]   2> 2490683 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid5]} 0 0
   [junit4]   2> 2490683 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid6]} 0 0
   [junit4]   2> 2490684 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2490684 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4e4516cf
   [junit4]   2> 2490701 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@549ca8c0[collection1] main]
   [junit4]   2> 2490702 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2490702 INFO  (searcherExecutor-11383-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@549ca8c0[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):c1)
 Uninverting(_1(6.7.0):c6)))}
   [junit4]   2> 2490703 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 19
   [junit4]   2> 2490704 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 2490713 ERROR 
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] 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":0},
   [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> 2490714 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 2490714 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] 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> 2490714 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 2490714 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=179845038
   [junit4]   2> 2490714 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 2490715 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 2490717 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 2490717 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 2490721 INFO  (coreCloseExecutor-11387-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@4b6b34f2
   [junit4]   2> 2490722 INFO  (coreCloseExecutor-11387-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 2490725 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[68B87FEE659909DA])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testMultipleSearchResults
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestUseDocValuesAsStored -Dtests.method=testMultipleSearchResults 
-Dtests.seed=68B87FEE659909DA -Dtests.slow=true -Dtests.locale=gd 
-Dtests.timezone=America/Barbados -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   0.32s J1 | 
TestUseDocValuesAsStored.testMultipleSearchResults <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: mismatch: 
'myid1'!='myid' @ response/docs/[0]/id
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([68B87FEE659909DA:5A9278709D672D03]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:983)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:930)
   [junit4]    >        at 
org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults(TestUseDocValuesAsStored.java:243)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]    >        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]    >        at 
java.base/java.lang.reflect.Method.invoke(Method.java:564)
   [junit4]    >        at java.base/java.lang.Thread.run(Thread.java:844)
   [junit4]   2> 2490728 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued
   [junit4]   2> 2490744 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 2490754 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, 
use <mergePolicyFactory> instead.
   [junit4]   2> 2490755 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 2490760 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2490764 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 2490771 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 2490772 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001\tempDir-003\collection1\conf\managed-schema
   [junit4]   2> 2490775 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001\tempDir-003\collection1\conf\schema-non-stored-docvalues.xml
 to 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001\tempDir-003\collection1\conf\schema-non-stored-docvalues.xml.bak
   [junit4]   2> 2490777 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 2490826 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@382549d3
   [junit4]   2> 2490831 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@382549d3
   [junit4]   2> 2490831 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@382549d3
   [junit4]   2> 2490848 WARN  (coreLoadExecutor-11394-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 2490849 INFO  (coreLoadExecutor-11394-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 2490860 INFO  (coreLoadExecutor-11394-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
   [junit4]   2> 2490868 INFO  (coreLoadExecutor-11394-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 2490869 INFO  (coreLoadExecutor-11394-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001\tempDir-003\collection1,
 trusted=true
   [junit4]   2> 2490869 INFO  (coreLoadExecutor-11394-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 
'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@382549d3
   [junit4]   2> 2490869 INFO  (coreLoadExecutor-11394-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2490869 INFO  (coreLoadExecutor-11394-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001\tempDir-003\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001\init-core-data-001\]
   [junit4]   2> 2490878 WARN  (coreLoadExecutor-11394-thread-1) [    ] 
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = 
requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, registerPath=/solr,/v2, 
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 2491012 INFO  (coreLoadExecutor-11394-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2491012 INFO  (coreLoadExecutor-11394-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2491016 INFO  (coreLoadExecutor-11394-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=26, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=22.2724609375, 
floorSegmentMB=0.609375, forceMergeDeletesPctAllowed=9.071143353586825, 
segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 2491020 INFO  (coreLoadExecutor-11394-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@534d0dca[collection1] main]
   [junit4]   2> 2491021 INFO  (coreLoadExecutor-11394-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001\tempDir-003\collection1\conf
   [junit4]   2> 2491021 INFO  (coreLoadExecutor-11394-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2491021 INFO  (searcherExecutor-11395-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@534d0dca[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):c1)
 Uninverting(_1(6.7.0):c6)))}
   [junit4]   2> 2491024 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 2491024 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 2491025 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491025 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 0
   [junit4]   2> 2491026 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2491026 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3f1e144
   [junit4]   2> 2491061 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@34cfab74[collection1] main]
   [junit4]   2> 2491062 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2491062 INFO  (searcherExecutor-11395-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@34cfab74[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.7.0):c1)))}
   [junit4]   2> 2491065 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 38
   [junit4]   2> 2491065 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491066 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491067 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491068 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491069 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491069 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491073 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 1
   [junit4]   2> 2491074 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2491074 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3f1e144
   [junit4]   2> 2491090 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2316ddf8[collection1] main]
   [junit4]   2> 2491090 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2491090 INFO  (searcherExecutor-11395-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2316ddf8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.7.0):c1)
 Uninverting(_3(6.7.0):c1)))}
   [junit4]   2> 2491090 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 17
   [junit4]   2> 2491090 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491090 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491090 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491095 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491096 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491097 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 2491099 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 1
   [junit4]   2> 2491100 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2491101 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3f1e144
   [junit4]   2> 2491115 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3e05769c[collection1] main]
   [junit4]   2> 2491116 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2491116 INFO  (searcherExecutor-11395-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3e05769c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.7.0):c1)
 Uninverting(_3(6.7.0):c1) Uninverting(_4(6.7.0):c1)))}
   [junit4]   2> 2491116 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 15
   [junit4]   2> 2491117 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_ds_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491119 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_ds_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491121 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491123 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491124 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_ds_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491126 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 2491129 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[4]} 0 1
   [junit4]   2> 2491129 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2491129 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3f1e144
   [junit4]   2> 2491149 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@50cdf97[collection1] main]
   [junit4]   2> 2491150 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2491150 INFO  (searcherExecutor-11395-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@50cdf97[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.7.0):c1)
 Uninverting(_3(6.7.0):c1) Uninverting(_4(6.7.0):c1) 
Uninverting(_5(6.7.0):c1)))}
   [junit4]   2> 2491151 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 21
   [junit4]   2> 2491152 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_ls_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491153 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_ls_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491154 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491156 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491157 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_ls_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491159 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 2491161 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 0
   [junit4]   2> 2491162 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2491162 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3f1e144
   [junit4]   2> 2491179 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3bc003b1[collection1] main]
   [junit4]   2> 2491180 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2491180 INFO  (searcherExecutor-11395-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3bc003b1[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.7.0):c1)
 Uninverting(_3(6.7.0):c1) Uninverting(_4(6.7.0):c1) Uninverting(_5(6.7.0):c1) 
Uninverting(_6(6.7.0):c1)))}
   [junit4]   2> 2491180 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 19
   [junit4]   2> 2491181 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491182 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491183 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491184 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491185 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491186 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 2491188 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[6]} 0 0
   [junit4]   2> 2491188 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2491188 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3f1e144
   [junit4]   2> 2491217 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7ec68539[collection1] main]
   [junit4]   2> 2491218 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2491219 INFO  (searcherExecutor-11395-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7ec68539[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.7.0):c1)
 Uninverting(_3(6.7.0):c1) Uninverting(_4(6.7.0):c1) Uninverting(_5(6.7.0):c1) 
Uninverting(_6(6.7.0):c1) Uninverting(_7(6.7.0):c1)))}
   [junit4]   2> 2491219 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 30
   [junit4]   2> 2491220 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491221 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491222 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491222 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491222 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491222 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 2491227 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[7]} 0 1
   [junit4]   2> 2491227 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2491227 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3f1e144
   [junit4]   2> 2491247 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@611d73ed[collection1] main]
   [junit4]   2> 2491248 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2491248 INFO  (searcherExecutor-11395-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@611d73ed[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.7.0):c1)
 Uninverting(_3(6.7.0):c1) Uninverting(_4(6.7.0):c1) Uninverting(_5(6.7.0):c1) 
Uninverting(_6(6.7.0):c1) Uninverting(_7(6.7.0):c1) Uninverting(_8(6.7.0):c1)))}
   [junit4]   2> 2491248 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 21
   [junit4]   2> 2491249 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491250 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491251 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491252 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491253 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491254 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 2491256 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[8]} 0 0
   [junit4]   2> 2491256 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2491256 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3f1e144
   [junit4]   2> 2491292 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@bb516fa[collection1] main]
   [junit4]   2> 2491292 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2491293 INFO  (searcherExecutor-11395-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@bb516fa[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.7.0):c1)
 Uninverting(_3(6.7.0):c1) Uninverting(_4(6.7.0):c1) Uninverting(_5(6.7.0):c1) 
Uninverting(_6(6.7.0):c1) Uninverting(_7(6.7.0):c1) Uninverting(_8(6.7.0):c1) 
Uninverting(_9(6.7.0):c1)))}
   [junit4]   2> 2491293 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 36
   [junit4]   2> 2491294 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491296 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491299 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491300 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491301 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491302 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
   [junit4]   2> 2491304 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[9]} 0 1
   [junit4]   2> 2491305 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2491306 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3f1e144
   [junit4]   2> 2491326 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4cc706cf[collection1] main]
   [junit4]   2> 2491327 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2491328 INFO  (searcherExecutor-11395-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4cc706cf[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.7.0):c1)
 Uninverting(_3(6.7.0):c1) Uninverting(_4(6.7.0):c1) Uninverting(_5(6.7.0):c1) 
Uninverting(_6(6.7.0):c1) Uninverting(_7(6.7.0):c1) Uninverting(_8(6.7.0):c1) 
Uninverting(_9(6.7.0):c1) Uninverting(_a(6.7.0):c1)))}
   [junit4]   2> 2491328 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 22
   [junit4]   2> 2491329 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=enums_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491331 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=enums_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491332 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491334 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491335 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=enums_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491336 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=9 status=0 QTime=0
   [junit4]   2> 2491338 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 2491339 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491340 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[10]} 0 0
   [junit4]   2> 2491340 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2491341 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3f1e144
   [junit4]   2> 2491354 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5a69e27b[collection1] main]
   [junit4]   2> 2491355 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2491356 INFO  (searcherExecutor-11395-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5a69e27b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.7.0):c1)))}
   [junit4]   2> 2491362 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 22
   [junit4]   2> 2491363 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=test_s_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491364 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=test_s_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491365 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491366 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491366 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=test_s_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491381 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491383 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[11]} 0 1
   [junit4]   2> 2491383 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2491384 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3f1e144
   [junit4]   2> 2491397 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@24dab1af[collection1] main]
   [junit4]   2> 2491398 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2491399 INFO  (searcherExecutor-11395-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@24dab1af[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.7.0):c1)
 Uninverting(_c(6.7.0):c1)))}
   [junit4]   2> 2491399 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 15
   [junit4]   2> 2491400 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491401 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491402 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491404 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491405 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491405 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 2491408 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[12]} 0 0
   [junit4]   2> 2491409 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2491409 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3f1e144
   [junit4]   2> 2491439 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@55c562cb[collection1] main]
   [junit4]   2> 2491439 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2491439 INFO  (searcherExecutor-11395-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@55c562cb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.7.0):c1)
 Uninverting(_c(6.7.0):c1) Uninverting(_d(6.7.0):c1)))}
   [junit4]   2> 2491440 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 31
   [junit4]   2> 2491440 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=test_ds_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491442 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=test_ds_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491443 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491444 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491446 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=test_ds_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491456 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 2491459 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[13]} 0 0
   [junit4]   2> 2491459 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2491460 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3f1e144
   [junit4]   2> 2491476 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@29d3d75e[collection1] main]
   [junit4]   2> 2491477 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2491478 INFO  (searcherExecutor-11395-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@29d3d75e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.7.0):c1)
 Uninverting(_c(6.7.0):c1) Uninverting(_d(6.7.0):c1) 
Uninverting(_e(6.7.0):c1)))}
   [junit4]   2> 2491478 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 18
   [junit4]   2> 2491479 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491481 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491482 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491484 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491485 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491487 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 2491490 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[14]} 0 1
   [junit4]   2> 2491490 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2491490 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3f1e144
   [junit4]   2> 2491518 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7753d7e7[collection1] main]
   [junit4]   2> 2491518 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2491519 INFO  (searcherExecutor-11395-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7753d7e7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.7.0):c1)
 Uninverting(_c(6.7.0):c1) Uninverting(_d(6.7.0):c1) Uninverting(_e(6.7.0):c1) 
Uninverting(_f(6.7.0):c1)))}
   [junit4]   2> 2491519 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 28
   [junit4]   2> 2491519 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_f_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491520 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_f_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491521 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491522 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491522 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_f_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491523 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 2491524 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[15]} 0 0
   [junit4]   2> 2491524 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2491524 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3f1e144
   [junit4]   2> 2491542 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@27e5210d[collection1] main]
   [junit4]   2> 2491543 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2491543 INFO  (searcherExecutor-11395-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@27e5210d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.7.0):c1)
 Uninverting(_c(6.7.0):c1) Uninverting(_d(6.7.0):c1) Uninverting(_e(6.7.0):c1) 
Uninverting(_f(6.7.0):c1) Uninverting(_g(6.7.0):c1)))}
   [junit4]   2> 2491544 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 19
   [junit4]   2> 2491544 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:15&fl=test_dt_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491546 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:15&fl=test_dt_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491547 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491548 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] 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> 2491548 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:15&fl=test_dt_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2491549 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 2491551 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[16]} 0 1
   [junit4]   2> 2491552 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2491552 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3f1e144
   [junit4]   2> 2491578 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1579dea[collection1] main]
   [junit4]   2> 2491579 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2491579 INFO  (searcherExecutor-11395-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1579dea[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.7.0):c1)
 Uninverting(_c(6.7.0):c1) Uninverting(_d(6.7.0):c1) Uninverting(_e(6.7.0):c1) 
Uninverting(_f(6.7.0):c1) Uninverting(_g(6.7.0):c1) Uninverting(_h(6.7.0):c1)))}
   [junit4]   2> 2491579 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[68B87FEE659909DA])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 26
   [junit4]   2> 2491580 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiVal

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

on&indent=true}{deleteByQuery=*:*} 0 0
   [junit4]   2> 2494268 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[68B87FEE659909DA]) 
[    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 2494269 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[68B87FEE659909DA]) 
[    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=566422453
   [junit4]   2> 2494269 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[68B87FEE659909DA]) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 2494270 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[68B87FEE659909DA]) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 2494272 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[68B87FEE659909DA]) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 2494273 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[68B87FEE659909DA]) 
[    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 2494274 INFO  (coreCloseExecutor-11435-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1a9c910d
   [junit4]   2> 2494275 INFO  (coreCloseExecutor-11435-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 2494278 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[68B87FEE659909DA]) 
[    ] o.a.s.SolrTestCaseJ4 ###Ending testMultivaluedOrdering
   [junit4]   2> 2494280 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[68B87FEE659909DA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_68B87FEE659909DA-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): {}, 
docValues:{}, maxPointsInLeafNode=1593, maxMBSortInHeap=7.594199726943218, 
sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=gd, 
timezone=America/Barbados
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 9-ea 
(64-bit)/cpus=3,threads=1,free=298283920,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [TestGraphMLResponseWriter, 
TestBadConfig, JSONWriterTest, HLLSerializationTest, 
LeaderInitiatedRecoveryOnShardRestartTest, 
PreAnalyzedFieldManagedSchemaCloudTest, SparseHLLTest, TestBlobHandler, 
MergeStrategyTest, TestTrie, ConnectionReuseTest, TestDistributedMissingSort, 
TestRangeQuery, WordBreakSolrSpellCheckerTest, PreAnalyzedFieldTest, 
DebugComponentTest, TestSolrCoreParser, HdfsRecoveryZkTest, 
TestDeleteCollectionOnDownNodes, HdfsBasicDistributedZkTest, 
CollectionsAPIDistributedZkTest, TestSerializedLuceneMatchVersion, 
AutoCommitTest, SharedFSAutoReplicaFailoverUtilsTest, TestSchemaManager, 
TestJmxMonitoredMap, TestFreeTextSuggestions, CoreMergeIndexesAdminHandlerTest, 
TestManagedResource, TestNestedDocsSort, TestInPlaceUpdatesDistrib, 
DistributedVersionInfoTest, TestCoreDiscovery, CdcrReplicationHandlerTest, 
TestDelegationWithHadoopAuth, TestAuthorizationFramework, 
TestDistributedGrouping, TestConfig, TestMaxTokenLenTokenizer, 
TestUninvertingReader, TestConfigSetProperties, TestConfigSetsAPIZkFailure, 
SuggestComponentTest, DistanceUnitsTest, TestFastWriter, DeleteNodeTest, 
FileBasedSpellCheckerTest, FieldAnalysisRequestHandlerTest, 
ShowFileRequestHandlerTest, TestExceedMaxTermLength, 
TestPHPSerializedResponseWriter, TermVectorComponentTest, 
OpenCloseCoreStressTest, TestScoreJoinQPScore, SolrMetricReporterTest, 
NotRequiredUniqueKeyTest, SignatureUpdateProcessorFactoryTest, 
WrapperMergePolicyFactoryTest, TestSolrCloudWithDelegationTokens, TestRecovery, 
TestExactSharedStatsCache, TestSSLRandomization, TestFieldCache, 
TestCloudDeleteByQuery, DefaultValueUpdateProcessorTest, ZkStateWriterTest, 
TestSearcherReuse, ConvertedLegacyTest, TestAtomicUpdateErrorCases, 
TestSuggestSpellingConverter, CdcrVersionReplicationTest, MinimalSchemaTest, 
TestCollapseQParserPlugin, TestFieldCacheSortRandom, 
SpellCheckCollatorWithCollapseTest, AddSchemaFieldsUpdateProcessorFactoryTest, 
VMParamsZkACLAndCredentialsProvidersTest, TlogReplayBufferedWhileIndexingTest, 
CollectionTooManyReplicasTest, DistribJoinFromCollectionTest, BJQParserTest, 
FieldMutatingUpdateProcessorTest, CreateCollectionCleanupTest, TestFiltering, 
SpellCheckCollatorTest, CdcrBootstrapTest, 
TestLMJelinekMercerSimilarityFactory, RecoveryAfterSoftCommitTest, 
TestQueryWrapperFilter, HdfsTlogReplayBufferedWhileIndexingTest, 
TestInfoStreamLogging, TestSolrCloudWithSecureImpersonation, 
RestartWhileUpdatingTest, TestSQLHandler, EchoParamsTest, 
TestPerFieldSimilarityWithDefaultOverride, TestRebalanceLeaders, 
ResponseHeaderTest, TestSystemIdResolver, TestDocSet, TestClusterProperties, 
DataDrivenBlockJoinTest, TestRandomFlRTGCloud, LeaderElectionIntegrationTest, 
TestNumericTerms32, ConfigSetsAPITest, CopyFieldTest, 
HdfsChaosMonkeySafeLeaderTest, RuleEngineTest, CoreAdminHandlerTest, 
DistributedTermsComponentTest, CoreSorterTest, TestReload, 
HighlighterConfigTest, SpellCheckComponentTest, AnalysisErrorHandlingTest, 
ReturnFieldsTest, OverseerCollectionConfigSetProcessorTest, UUIDFieldTest, 
MultiThreadedOCPTest, TestSolrQueryParserResource, 
AsyncCallRequestStatusResponseTest, BinaryUpdateRequestHandlerTest, 
TestFoldingMultitermQuery, RegexBoostProcessorTest, SolrJmxReporterTest, 
BasicFunctionalityTest, DistributedQueryComponentOptimizationTest, 
BlockJoinFacetRandomTest, DistanceFunctionTest, SortSpecParsingTest, 
DateRangeFieldTest, TestInitQParser, TestRandomFaceting, SuggesterWFSTTest, 
TestValueSourceCache, TestDynamicFieldCollectionResource, 
TestManagedSynonymGraphFilterFactory, SortByFunctionTest, TestCodecSupport, 
SolrIndexMetricsTest, SolrCoreMetricManagerTest, TestDownShardTolerantSearch, 
TemplateUpdateProcessorTest, ReplaceNodeTest, TestHdfsCloudBackupRestore, 
TestCrossCoreJoin, TestCollectionAPIs, TestHdfsBackupRestoreCore, 
HdfsWriteToMultipleCollectionsTest, TestBlendedInfixSuggestions, 
DistributedFacetPivotLargeTest, OverseerTaskQueueTest, MBeansHandlerTest, 
TestMiniSolrCloudCluster, UpdateLogTest, BufferStoreTest, 
SolrGangliaReporterTest, LukeRequestHandlerTest, DistribCursorPagingTest, 
TestDefaultStatsCache, TestIndexSearcher, TestBulkSchemaConcurrent, 
ExternalFileFieldSortTest, SuggesterTSTTest, TestConfigSetsAPI, 
URLClassifyProcessorTest, TestShardHandlerFactory, CircularListTest, 
SSLMigrationTest, TestIndexingPerformance, TestClusterStateMutator, 
TestCorePropertiesReload, BasicZkTest, FullSolrCloudDistribCmdsTest, 
ClusterStateUpdateTest, LeaderElectionTest, TestZkChroot, ZkCLITest, 
ShardRoutingCustomTest, DistributedSpellCheckComponentTest, ZkControllerTest, 
TestRealTimeGet, TestJoin, TestCoreContainer, TestSolr4Spatial, 
SolrCmdDistributorTest, QueryElevationComponentTest, BadIndexSchemaTest, 
TestLazyCores, SolrIndexSplitterTest, SolrRequestParserTest, SuggesterTest, 
NoCacheHeaderTest, TestUpdate, QueryEqualityTest, TestRemoteStreaming, 
DocValuesMultiTest, TestSolrDeletionPolicy1, SolrInfoMBeanTest, 
TestSurroundQueryParser, TestQueryUtils, DirectSolrSpellCheckerTest, 
TestReversedWildcardFilterFactory, PrimitiveFieldTypeTest, TestOmitPositions, 
RequestHandlersTest, RequiredFieldsTest, FastVectorHighlighterTest, 
TestCollationField, UpdateRequestProcessorFactoryTest, TestCSVResponseWriter, 
JsonLoaderTest, CSVRequestHandlerTest, SearchHandlerTest, 
TestBinaryResponseWriter, UpdateParamsTest, TestQuerySenderNoQuery, 
SolrIndexConfigTest, TestSolrDeletionPolicy2, MultiTermTest, TestSearchPerf, 
TestElisionMultitermQuery, TestSolrCoreProperties, 
SpellPossibilityIteratorTest, TestDFRSimilarityFactory, 
TestIBSimilarityFactory, ResourceLoaderTest, PluginInfoTest, 
DateMathParserTest, SpellingQueryConverterTest, ClusterStateTest, TestLRUCache, 
SliceStateTest, TestRTGBase, SolrTestCaseJ4Test, 
TestSimpleTrackingShardHandler, TestPathTrie, 
TestEmbeddedSolrServerConstructors, TestEmbeddedSolrServerSchemaAPI, 
AliasIntegrationTest, CloudExitableDirectoryReaderTest, CollectionReloadTest, 
CollectionsAPISolrJTest, HttpPartitionTest, LeaderElectionContextKeyTest, 
LeaderFailoverAfterPartitionTest, LeaderInitiatedRecoveryOnCommitTest, 
MissingSegmentRecoveryTest, OutOfBoxZkACLAndCredentialsProvidersTest, 
OverseerModifyCollectionTest, OverseerStatusTest, 
SimpleCollectionCreateDeleteTest, SolrCLIZkUtilsTest, SolrXmlInZkTest, 
TestCloudJSONFacetJoinDomain, TestCloudPivotFacet, TestCloudPseudoReturnFields, 
TestCloudRecovery, TestCollectionAPI, TestCryptoKeys, 
TestDistribDocBasedVersion, TestExclusionRuleCollectionAccess, 
TestLeaderElectionZkExpiry, TestLeaderInitiatedRecoveryThread, 
TestLocalFSCloudBackupRestore, TestReplicaProperties, TestRequestForwarding, 
TestShortCircuitedRequests, TestSolrCloudWithKerberosAlt, 
TestStressCloudBlindAtomicUpdates, TestStressInPlaceUpdates, 
CachingDirectoryFactoryTest, TestInitParams, TestNRTOpen, 
TestReloadAndDeleteDocs, TestSolrConfigHandler, TestSolrXml, 
TestSolrCloudSnapshots, RequestLoggingTest, TestReplicationHandlerBackup, 
TestSolrConfigHandlerCloud, DistributedFacetPivotLongTailTest, 
DistributedFacetPivotWhiteBoxTest, DistributedSuggestComponentTest, 
TestDistributedStatsComponentCardinality, SubstringBytesRefFilterTest, 
TestIntervalFaceting, TestBinaryResponseWriter, TestPushWriter, 
TestRawTransformer, TestChildDocTransformer, TestClassNameShortening, 
TestCopyFieldCollectionResource, TestFieldTypeCollectionResource, 
TestSchemaNameResource, TestSchemaResource, 
TestSolrQueryParserDefaultOperatorResource, TestManagedSynonymFilterFactory, 
BadCopyFieldTest, BooleanFieldTest, EnumFieldTest, 
ManagedSchemaRoundRobinCloudTest, TestHalfAndHalfDocValues, TestManagedSchema, 
TestUseDocValuesAsStored]
   [junit4] Completed [637/715 (1!)] on J1 in 4.21s, 6 tests, 1 error <<< 
FAILURES!

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

Reply via email to