Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.6-Windows/5/
Java: 64bit/jdk-9-ea+171 -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([275532060001E6EC:157F3598F8FFC235]: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:563)
        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 13054 lines...]
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4]   2> Creating dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001\init-core-data-001
   [junit4]   2> 2268102 WARN  
(SUITE-TestUseDocValuesAsStored-seed#[275532060001E6EC]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 2268103 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[275532060001E6EC]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 2268113 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[275532060001E6EC]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) 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> 2268114 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testOnEmptyIndex
   [junit4]   2> 2268122 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 2268131 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 2268136 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
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> 2268139 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 2268148 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 2268151 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001\tempDir-001\collection1\conf\managed-schema
   [junit4]   2> 2268160 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed 
the non-managed schema 
C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001\tempDir-001\collection1\conf\schema-non-stored-docvalues.xml
 to 
C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001\tempDir-001\collection1\conf\schema-non-stored-docvalues.xml.bak
   [junit4]   2> 2268165 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 2268202 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c9389bd
   [junit4]   2> 2268207 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c9389bd
   [junit4]   2> 2268207 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c9389bd
   [junit4]   2> 2268219 INFO  (coreLoadExecutor-11399-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 2268225 INFO  (coreLoadExecutor-11399-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
   [junit4]   2> 2268231 INFO  (coreLoadExecutor-11399-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 2268231 INFO  (coreLoadExecutor-11399-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001\tempDir-001\collection1,
 trusted=true
   [junit4]   2> 2268231 INFO  (coreLoadExecutor-11399-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@3c9389bd
   [junit4]   2> 2268232 INFO  (coreLoadExecutor-11399-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2268232 INFO  (coreLoadExecutor-11399-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001\tempDir-001\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001\init-core-data-001\]
   [junit4]   2> 2268238 INFO  (coreLoadExecutor-11399-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=14, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5873994744313168]
   [junit4]   2> 2268244 WARN  (coreLoadExecutor-11399-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> 2268307 INFO  (coreLoadExecutor-11399-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2268307 INFO  (coreLoadExecutor-11399-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2268308 INFO  (coreLoadExecutor-11399-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=23, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=12.7587890625, 
floorSegmentMB=1.2255859375, forceMergeDeletesPctAllowed=29.629551880599738, 
segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 2268308 INFO  (coreLoadExecutor-11399-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@749b04f6[collection1] main]
   [junit4]   2> 2268309 INFO  (coreLoadExecutor-11399-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: 
C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001\tempDir-001\collection1\conf
   [junit4]   2> 2268309 INFO  (coreLoadExecutor-11399-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2268309 INFO  (searcherExecutor-11400-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@749b04f6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2268312 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 2268312 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 2268312 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
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> 2268312 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2268312 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7eb60ac4
   [junit4]   2> 2268314 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@549b1b3a[collection1] main]
   [junit4]   2> 2268315 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2268315 INFO  (searcherExecutor-11400-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@549b1b3a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2268315 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 3
   [junit4]   2> 2268318 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 2268319 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 2268320 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 2268320 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*,test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 2268321 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[xyz]} 0 0
   [junit4]   2> 2268321 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 2268322 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 2268322 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 2268323 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*,test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 2268323 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2268323 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7eb60ac4
   [junit4]   2> 2268352 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@6e58b5f0[collection1] main]
   [junit4]   2> 2268353 INFO  (searcherExecutor-11400-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6e58b5f0[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C1)))}
   [junit4]   2> 2268353 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2268353 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 29
   [junit4]   2> 2268353 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 2268354 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2268354 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=test_nonstored_dv_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2268354 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*,test_nonstored_dv_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2268378 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[xyz]} 0 9
   [junit4]   2> 2268378 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2268378 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7eb60ac4
   [junit4]   2> 2268411 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@66ed6970[collection1] main]
   [junit4]   2> 2268411 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2268411 INFO  (searcherExecutor-11400-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@66ed6970[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.6.0):C1)))}
   [junit4]   2> 2268414 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 36
   [junit4]   2> 2268415 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 2268416 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 2268416 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
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> 2268416 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 2268416 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=742489022
   [junit4]   2> 2268416 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 2268418 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 2268419 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 2268419 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 2268419 INFO  (coreCloseExecutor-11404-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1b39de31
   [junit4]   2> 2268420 INFO  (coreCloseExecutor-11404-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 2268423 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[275532060001E6EC]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testOnEmptyIndex
   [junit4]   2> 2268424 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.SolrTestCaseJ4 ###Starting testMultivaluedOrdering
   [junit4]   2> 2268444 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 2268452 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 2268453 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] 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> 2268460 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 2268467 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 2268469 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001\tempDir-002\collection1\conf\managed-schema
   [junit4]   2> 2268486 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001\tempDir-002\collection1\conf\schema-non-stored-docvalues.xml
 to 
C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001\tempDir-002\collection1\conf\schema-non-stored-docvalues.xml.bak
   [junit4]   2> 2268488 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 2268533 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c9389bd
   [junit4]   2> 2268535 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c9389bd
   [junit4]   2> 2268535 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c9389bd
   [junit4]   2> 2268542 INFO  (coreLoadExecutor-11411-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 2268547 INFO  (coreLoadExecutor-11411-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
   [junit4]   2> 2268563 INFO  (coreLoadExecutor-11411-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 2268563 INFO  (coreLoadExecutor-11411-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001\tempDir-002\collection1,
 trusted=true
   [junit4]   2> 2268564 INFO  (coreLoadExecutor-11411-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@3c9389bd
   [junit4]   2> 2268564 INFO  (coreLoadExecutor-11411-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2268564 INFO  (coreLoadExecutor-11411-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001\tempDir-002\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001\init-core-data-001\]
   [junit4]   2> 2268569 WARN  (coreLoadExecutor-11411-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> 2268620 INFO  (coreLoadExecutor-11411-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2268620 INFO  (coreLoadExecutor-11411-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2268622 INFO  (coreLoadExecutor-11411-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=14, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5873994744313168]
   [junit4]   2> 2268622 INFO  (coreLoadExecutor-11411-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@616b289c[collection1] main]
   [junit4]   2> 2268622 INFO  (coreLoadExecutor-11411-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: 
C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001\tempDir-002\collection1\conf
   [junit4]   2> 2268622 INFO  (coreLoadExecutor-11411-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2268622 INFO  (searcherExecutor-11412-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@616b289c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.6.0):C1)))}
   [junit4]   2> 2268626 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 2268626 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 2268626 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] 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> 2268626 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[1]} 0 0
   [junit4]   2> 2268626 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[2]} 0 0
   [junit4]   2> 2268628 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[3]} 0 0
   [junit4]   2> 2268628 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[4]} 0 0
   [junit4]   2> 2268628 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2268628 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@2d187349
   [junit4]   2> 2268640 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5ca3a5ce[collection1] main]
   [junit4]   2> 2268641 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2268641 INFO  (searcherExecutor-11412-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5ca3a5ce[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):C4)))}
   [junit4]   2> 2268643 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 14
   [junit4]   2> 2268643 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_mvt_dvt_st_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2268644 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_mvt_dvt_sf_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2268645 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_mvt_dvf_st_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2268646 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_mvt_dvu_st_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2268646 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 2268646 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] 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> 2268646 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 2268646 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1474928925
   [junit4]   2> 2268646 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 2268648 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 2268648 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 2268648 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 2268649 INFO  (coreCloseExecutor-11416-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@16e486f4
   [junit4]   2> 2268652 INFO  (coreCloseExecutor-11416-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 2268654 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[275532060001E6EC]) 
[    ] o.a.s.SolrTestCaseJ4 ###Ending testMultivaluedOrdering
   [junit4]   2> 2268657 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued
   [junit4]   2> 2268668 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 2268677 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 2268681 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268687 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 2268695 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 2268696 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001\tempDir-003\collection1\conf\managed-schema
   [junit4]   2> 2268699 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001\tempDir-003\collection1\conf\schema-non-stored-docvalues.xml
 to 
C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001\tempDir-003\collection1\conf\schema-non-stored-docvalues.xml.bak
   [junit4]   2> 2268700 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 2268738 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c9389bd
   [junit4]   2> 2268742 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c9389bd
   [junit4]   2> 2268742 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c9389bd
   [junit4]   2> 2268752 INFO  (coreLoadExecutor-11423-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 2268760 INFO  (coreLoadExecutor-11423-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
   [junit4]   2> 2268767 INFO  (coreLoadExecutor-11423-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 2268767 INFO  (coreLoadExecutor-11423-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001\tempDir-003\collection1,
 trusted=true
   [junit4]   2> 2268767 INFO  (coreLoadExecutor-11423-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@3c9389bd
   [junit4]   2> 2268768 INFO  (coreLoadExecutor-11423-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2268768 INFO  (coreLoadExecutor-11423-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001\tempDir-003\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001\init-core-data-001\]
   [junit4]   2> 2268775 WARN  (coreLoadExecutor-11423-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> 2268840 INFO  (coreLoadExecutor-11423-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2268840 INFO  (coreLoadExecutor-11423-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2268843 INFO  (coreLoadExecutor-11423-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=14, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5873994744313168]
   [junit4]   2> 2268846 INFO  (coreLoadExecutor-11423-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@6d9e923d[collection1] main]
   [junit4]   2> 2268846 INFO  (coreLoadExecutor-11423-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: 
C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001\tempDir-003\collection1\conf
   [junit4]   2> 2268847 INFO  (coreLoadExecutor-11423-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2268847 INFO  (searcherExecutor-11424-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6d9e923d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):C4)))}
   [junit4]   2> 2268848 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 2268848 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 2268848 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268849 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 0
   [junit4]   2> 2268850 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2268850 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@72555c4e
   [junit4]   2> 2268865 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7a817367[collection1] main]
   [junit4]   2> 2268867 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2268867 INFO  (searcherExecutor-11424-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7a817367[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.6.0):C1)))}
   [junit4]   2> 2268869 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 18
   [junit4]   2> 2268870 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268871 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268871 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268872 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268872 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268873 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2268874 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 0
   [junit4]   2> 2268875 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2268875 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@72555c4e
   [junit4]   2> 2268890 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@725366e2[collection1] main]
   [junit4]   2> 2268890 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2268892 INFO  (searcherExecutor-11424-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@725366e2[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.6.0):C1)
 Uninverting(_4(6.6.0):C1)))}
   [junit4]   2> 2268892 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 16
   [junit4]   2> 2268893 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268894 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268895 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268895 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268896 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268896 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 2268897 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 0
   [junit4]   2> 2268897 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2268897 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@72555c4e
   [junit4]   2> 2268915 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2a737516[collection1] main]
   [junit4]   2> 2268915 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2268916 INFO  (searcherExecutor-11424-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2a737516[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.6.0):C1)
 Uninverting(_4(6.6.0):C1) Uninverting(_5(6.6.0):C1)))}
   [junit4]   2> 2268916 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 18
   [junit4]   2> 2268916 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_d_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2268917 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_d_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2268918 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268918 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268919 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_d_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2268920 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 2268922 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[4]} 0 0
   [junit4]   2> 2268922 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2268922 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@72555c4e
   [junit4]   2> 2268937 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5a663e6b[collection1] main]
   [junit4]   2> 2268937 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2268938 INFO  (searcherExecutor-11424-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5a663e6b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.6.0):C1)
 Uninverting(_4(6.6.0):C1) Uninverting(_5(6.6.0):C1) 
Uninverting(_6(6.6.0):C1)))}
   [junit4]   2> 2268938 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 16
   [junit4]   2> 2268938 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268939 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268940 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268942 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268943 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268944 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 2268945 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 0
   [junit4]   2> 2268945 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2268946 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@72555c4e
   [junit4]   2> 2268960 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6906852d[collection1] main]
   [junit4]   2> 2268960 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2268962 INFO  (searcherExecutor-11424-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6906852d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.6.0):C1)
 Uninverting(_4(6.6.0):C1) Uninverting(_5(6.6.0):C1) Uninverting(_6(6.6.0):C1) 
Uninverting(_7(6.6.0):C1)))}
   [junit4]   2> 2268962 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 15
   [junit4]   2> 2268963 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268963 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268963 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268963 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268964 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268964 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 2268965 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[6]} 0 0
   [junit4]   2> 2268965 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2268965 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@72555c4e
   [junit4]   2> 2268987 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2931bbfa[collection1] main]
   [junit4]   2> 2268987 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2268988 INFO  (searcherExecutor-11424-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2931bbfa[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.6.0):C1)
 Uninverting(_4(6.6.0):C1) Uninverting(_5(6.6.0):C1) Uninverting(_6(6.6.0):C1) 
Uninverting(_7(6.6.0):C1) Uninverting(_8(6.6.0):C1)))}
   [junit4]   2> 2268988 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 22
   [junit4]   2> 2268989 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268989 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268990 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268990 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268992 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2268992 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 2268994 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[7]} 0 0
   [junit4]   2> 2268994 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2268994 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@72555c4e
   [junit4]   2> 2269021 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2f41e357[collection1] main]
   [junit4]   2> 2269022 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2269022 INFO  (searcherExecutor-11424-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2f41e357[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.6.0):C1)
 Uninverting(_4(6.6.0):C1) Uninverting(_5(6.6.0):C1) Uninverting(_6(6.6.0):C1) 
Uninverting(_7(6.6.0):C1) Uninverting(_8(6.6.0):C1) Uninverting(_9(6.6.0):C1)))}
   [junit4]   2> 2269022 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 28
   [junit4]   2> 2269023 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_ss_dv&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2269024 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_ss_dv,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2269025 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269027 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269028 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_ss_dv,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2269029 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 2269031 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[8]} 0 0
   [junit4]   2> 2269031 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2269031 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@72555c4e
   [junit4]   2> 2269091 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@27079fbb[collection1] main]
   [junit4]   2> 2269092 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2269092 INFO  (searcherExecutor-11424-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@27079fbb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.6.0):C1)
 Uninverting(_4(6.6.0):C1) Uninverting(_5(6.6.0):C1) Uninverting(_6(6.6.0):C1) 
Uninverting(_7(6.6.0):C1) Uninverting(_8(6.6.0):C1) Uninverting(_9(6.6.0):C1) 
Uninverting(_a(6.6.0):C1)))}
   [junit4]   2> 2269093 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 61
   [junit4]   2> 2269094 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269095 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269096 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269098 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269099 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269100 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
   [junit4]   2> 2269102 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[9]} 0 0
   [junit4]   2> 2269103 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2269103 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@72555c4e
   [junit4]   2> 2269180 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@ea82b3a[collection1] main]
   [junit4]   2> 2269180 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2269182 INFO  (searcherExecutor-11424-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@ea82b3a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.6.0):C1)
 Uninverting(_4(6.6.0):C1) Uninverting(_5(6.6.0):C1) Uninverting(_6(6.6.0):C1) 
Uninverting(_7(6.6.0):C1) Uninverting(_8(6.6.0):C1) Uninverting(_9(6.6.0):C1) 
Uninverting(_a(6.6.0):C1) Uninverting(_b(6.6.0):C1)))}
   [junit4]   2> 2269182 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 79
   [junit4]   2> 2269182 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269183 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269184 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269184 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269185 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269185 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=9 status=0 QTime=0
   [junit4]   2> 2269200 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 2269200 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269200 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[10]} 0 0
   [junit4]   2> 2269202 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2269202 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@72555c4e
   [junit4]   2> 2269218 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6a6abbbd[collection1] main]
   [junit4]   2> 2269218 INFO  (searcherExecutor-11424-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6a6abbbd[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_c(6.6.0):C1)))}
   [junit4]   2> 2269220 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2269233 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 31
   [junit4]   2> 2269234 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269234 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269235 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269237 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269238 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269239 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2269240 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[11]} 0 0
   [junit4]   2> 2269240 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2269240 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@72555c4e
   [junit4]   2> 2269275 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2136875c[collection1] main]
   [junit4]   2> 2269275 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2269276 INFO  (searcherExecutor-11424-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2136875c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_c(6.6.0):C1)
 Uninverting(_d(6.6.0):C1)))}
   [junit4]   2> 2269276 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 36
   [junit4]   2> 2269277 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:11&fl=test_i_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2269277 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:11&fl=test_i_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2269278 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269278 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269279 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:11&fl=test_i_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2269279 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 2269282 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[12]} 0 1
   [junit4]   2> 2269282 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2269282 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@72555c4e
   [junit4]   2> 2269318 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4e8ee8f1[collection1] main]
   [junit4]   2> 2269318 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2269319 INFO  (searcherExecutor-11424-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4e8ee8f1[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_c(6.6.0):C1)
 Uninverting(_d(6.6.0):C1) Uninverting(_e(6.6.0):C1)))}
   [junit4]   2> 2269319 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 36
   [junit4]   2> 2269320 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269320 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269328 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269330 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269332 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269333 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 2269336 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[13]} 0 0
   [junit4]   2> 2269336 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2269336 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@72555c4e
   [junit4]   2> 2269362 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@40e8327[collection1] main]
   [junit4]   2> 2269375 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2269377 INFO  (searcherExecutor-11424-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@40e8327[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_c(6.6.0):C1)
 Uninverting(_d(6.6.0):C1) Uninverting(_e(6.6.0):C1) 
Uninverting(_f(6.6.0):C1)))}
   [junit4]   2> 2269377 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 41
   [junit4]   2> 2269377 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:13&fl=test_l_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2269378 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:13&fl=test_l_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2269378 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269379 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269379 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:13&fl=test_l_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2269380 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 2269382 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[14]} 0 0
   [junit4]   2> 2269382 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2269382 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@72555c4e
   [junit4]   2> 2269400 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@37e7901[collection1] main]
   [junit4]   2> 2269400 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2269402 INFO  (searcherExecutor-11424-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@37e7901[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_c(6.6.0):C1)
 Uninverting(_d(6.6.0):C1) Uninverting(_e(6.6.0):C1) Uninverting(_f(6.6.0):C1) 
Uninverting(_g(6.6.0):C1)))}
   [junit4]   2> 2269402 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 19
   [junit4]   2> 2269402 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_fs_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2269403 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_fs_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2269404 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269405 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269407 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_fs_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2269408 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 2269410 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[15]} 0 0
   [junit4]   2> 2269411 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2269411 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@72555c4e
   [junit4]   2> 2269448 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@a10f2e4[collection1] main]
   [junit4]   2> 2269454 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2269454 INFO  (searcherExecutor-11424-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@a10f2e4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_c(6.6.0):C1)
 Uninverting(_d(6.6.0):C1) Uninverting(_e(6.6.0):C1) Uninverting(_f(6.6.0):C1) 
Uninverting(_g(6.6.0):C1) Uninverting(_h(6.6.0):C1)))}
   [junit4]   2> 2269454 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 43
   [junit4]   2> 2269454 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:15&fl=test_dts_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2269454 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:15&fl=test_dts_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2269454 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269454 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] 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> 2269454 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:15&fl=test_dts_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2269461 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 2269463 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[16]} 0 0
   [junit4]   2> 2269463 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2269464 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[275532060001E6EC])
 [    ] o.a.s.u.SolrIndexWrit

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

oreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 2284474 INFO  (coreCloseExecutor-11464-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@4d4dd7fe
   [junit4]   2> 2284476 INFO  (coreCloseExecutor-11464-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 2284478 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[275532060001E6EC]) [    
] o.a.s.SolrTestCaseJ4 ###Ending testManagedSchema
   [junit4]   2> 2284479 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[275532060001E6EC]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
C:\Users\jenkins\workspace\Lucene-Solr-6.6-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_275532060001E6EC-001
   [junit4]   2> May 27, 2017 6:37:20 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 2 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Lucene62, 
sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=ti-ER, 
timezone=Europe/Zagreb
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 9-ea 
(64-bit)/cpus=3,threads=1,free=158978008,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [InfoHandlerTest, 
TestSolrDeletionPolicy1, TestSha256AuthenticationProvider, 
UnloadDistributedZkTest, TestAnalyzeInfixSuggestions, RankQueryTest, 
DistributedQueryComponentCustomSortTest, TestCloudNestedDocsSort, 
TestRawTransformer, TestMultiWordSynonyms, TestHashPartitioner, 
TestStressVersions, BasicDistributedZkTest, CdcrRequestHandlerTest, 
TestLockTree, JSONWriterTest, TestStressRecovery, CollectionStateFormat2Test, 
TestSortByMinMaxFunction, TestSolrConfigHandlerConcurrent, MoveReplicaTest, 
TestDocTermOrds, TestRequestStatusCollectionAPI, 
DeleteLastCustomShardedReplicaTest, SOLR749Test, 
TestLeaderElectionWithEmptyReplica, VersionInfoTest, RulesTest, 
TestExclusionRuleCollectionAccess, SynonymTokenizerTest, TestCharFilters, 
TestOnReconnectListenerSupport, TestJmxIntegration, AddBlockUpdateTest, 
TestReplicaProperties, DocValuesMultiTest, RecoveryZkTest, ZkControllerTest, 
SolrMetricReporterTest, TestFilteredDocIdSet, CdcrUpdateLogTest, 
HdfsBasicDistributedZk2Test, TestSchemaSimilarityResource, TestSimpleTextCodec, 
HdfsRestartWhileUpdatingTest, LeaderElectionTest, 
DocumentAnalysisRequestHandlerTest, OverseerModifyCollectionTest, 
TestPerFieldSimilarityClassic, TestScoreJoinQPNoScore, TestMaxScoreQueryParser, 
TestRandomDVFaceting, BlockJoinFacetSimpleTest, TestSolrDynamicMBean, 
ConnectionManagerTest, RollingRestartTest, TestHalfAndHalfDocValues, 
TestManagedSchemaAPI, TestXIncludeConfig, QueryElevationComponentTest, 
ManagedSchemaRoundRobinCloudTest, TestFieldCollectionResource, 
SuggestComponentContextFilterQueryTest, OverseerStatusTest, 
TestPerFieldSimilarity, TestSchemalessBufferedUpdates, TestSolrJ, 
TestImplicitCoreProperties, HdfsChaosMonkeyNothingIsSafeTest, 
XmlUpdateRequestHandlerTest, SolrJmxReporterTest, DirectSolrConnectionTest, 
ConjunctionSolrSpellCheckerTest, JsonValidatorTest, 
TestSerializedLuceneMatchVersion, PrimUtilsTest, TestStressLucene, 
TermsComponentTest, SaslZkACLProviderTest, JavabinLoaderTest, 
SimpleCollectionCreateDeleteTest, TestChildDocTransformer, 
PingRequestHandlerTest, DirectSolrSpellCheckerTest, TestBM25SimilarityFactory, 
TestDocBasedVersionConstraints, TestOverriddenPrefixQueryForCustomFieldType, 
DocValuesMissingTest, TestAnalyzedSuggestions, TestMacroExpander, 
TestInitParams, TestBinaryField, TestPostingsSolrHighlighter, 
TimeZoneUtilsTest, ChaosMonkeyNothingIsSafeTest, 
ParsingFieldUpdateProcessorsTest, TestGraphMLResponseWriter, 
PeerSyncReplicationTest, DistributedQueryElevationComponentTest, FullHLLTest, 
TestConfigsApi, SpatialRPTFieldTypeTest, ZkNodePropsTest, 
LeaderFailoverAfterPartitionTest, HttpPartitionTest, SolrCoreTest, 
DistributedFacetPivotSmallTest, TestUnifiedSolrHighlighter, TestPointFields, 
TestLeaderInitiatedRecoveryThread, TestConfigSetsAPIExclusivity, 
SimplePostToolTest, DirectoryFactoryTest, TestConfigSetImmutable, 
BigEndianAscendingWordDeserializerTest, HdfsThreadLeakTest, 
SolrCloudExampleTest, TestRestoreCore, TestEmbeddedSolrServerConstructors, 
SolrPluginUtilsTest, DistributedSuggestComponentTest, SolrGangliaReporterTest, 
TestWordDelimiterFilterFactory, TestHighlightDedupGrouping, 
DocExpirationUpdateProcessorFactoryTest, TestPayloadScoreQParserPlugin, 
HdfsDirectoryTest, TestAddFieldRealTimeGet, CloudExitableDirectoryReaderTest, 
TestJsonRequest, UniqFieldsUpdateProcessorFactoryTest, TestNumericTerms64, 
TestLRUCache, TestClassNameShortening, TestRTGBase, MetricUtilsTest, 
SegmentsInfoRequestHandlerTest, TestCollectionAPI, 
TestHighFrequencyDictionaryFactory, ActionThrottleTest, 
TestSurroundQueryParser, UUIDUpdateProcessorFallbackTest, DOMUtilTest, 
HighlighterTest, PrimitiveFieldTypeTest, TestGroupingSearch, TestExportWriter, 
BitVectorTest, CustomCollectionTest, SampleTest, SpatialFilterTest, 
TestXmlQParser, RequestLoggingTest, ShardRoutingTest, TestDistribIDF, 
OutputWriterTest, SmileWriterTest, PluginInfoTest, TestPKIAuthenticationPlugin, 
TestManagedStopFilterFactory, SimpleFacetsTest, 
DistributedFacetExistsSmallTest, TestRTimerTree, ConfigureRecoveryStrategyTest, 
BlockDirectoryTest, TestSolrQueryParserResource, DistributedDebugComponentTest, 
TestFastOutputStream, FastVectorHighlighterTest, HdfsUnloadDistributedZkTest, 
TestFieldCacheReopen, ReplicationFactorTest, NodeMutatorTest, 
CollectionsAPIDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, 
OverseerTest, BasicZkTest, TestReplicationHandler, ClusterStateUpdateTest, 
ZkSolrClientTest, TestZkChroot, ZkCLITest, ShardRoutingCustomTest, 
TestDistributedSearch, AutoCommitTest, TestDistributedGrouping, TestFaceting, 
TestRecovery, DistributedSpellCheckComponentTest, TestRealTimeGet, 
TestStressReorder, HardAutoCommitTest, TestCoreContainer, TestSolr4Spatial, 
StatsComponentTest, SolrCmdDistributorTest, PeerSyncTest, ConvertedLegacyTest, 
TestSort, TestFunctionQuery, TestLazyCores, DirectUpdateHandlerTest, 
TestIndexSearcher, SoftAutoCommitTest, ShowFileRequestHandlerTest, 
CurrencyFieldXmlFileTest, AnalysisAfterCoreReloadTest, 
SignatureUpdateProcessorFactoryTest, TestExtendedDismaxParser, 
SpellCheckCollatorTest, SuggesterFSTTest, CoreAdminHandlerTest, 
SolrRequestParserTest, DocValuesTest, SuggesterTest, TestTrie, TestCSVLoader, 
PolyFieldTest, WordBreakSolrSpellCheckerTest, 
SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, 
TestPseudoReturnFields, TestUpdate, TestAtomicUpdateErrorCases, 
QueryEqualityTest, DirectUpdateHandlerOptimizeTest, 
StatelessScriptUpdateProcessorFactoryTest, TestRemoteStreaming, 
SolrInfoMBeanTest, DebugComponentTest, DisMaxRequestHandlerTest, 
StandardRequestHandlerTest, TestReversedWildcardFilterFactory, 
TestOmitPositions, TestValueSourceCache, MoreLikeThisHandlerTest, 
IndexSchemaRuntimeFieldTest, IndexSchemaTest, ReturnFieldsTest, 
QueryParsingTest, TestPartialUpdateDeduplication, TestComponentsName, 
TestBinaryResponseWriter, TestLFUCache, TestFuzzyAnalyzedSuggestions, 
TestPhraseSuggestions, TestSweetSpotSimilarityFactory, TestFastWriter, 
ResourceLoaderTest, OpenExchangeRatesOrgProviderTest, TestFastLRUCache, 
TestSuggestSpellingConverter, DateFieldTest, ClusterStateTest, 
TestDocumentBuilder, CircularListTest, DistributedMLTComponentTest, 
SolrTestCaseJ4Test, TestCrossCoreJoin, TestSimpleTrackingShardHandler, 
CollectionTooManyReplicasTest, ConcurrentDeleteAndCreateCollectionTest, 
CreateCollectionCleanupTest, DeleteNodeTest, DeleteReplicaTest, 
DeleteStatusTest, DistribDocExpirationUpdateProcessorTest, 
DocValuesNotIndexedTest, LeaderElectionContextKeyTest, 
LeaderFailureAfterFreshStartTest, MigrateRouteKeyTest, 
OverriddenZkACLAndCredentialsProvidersTest, OverseerTaskQueueTest, 
RemoteQueryErrorTest, RestartWhileUpdatingTest, 
SharedFSAutoReplicaFailoverTest, SharedFSAutoReplicaFailoverUtilsTest, 
SolrXmlInZkTest, TestAuthenticationFramework, TestCloudDeleteByQuery, 
TestCloudPseudoReturnFields, TestCloudRecovery, TestSegmentSorting, 
TestSolrCloudWithKerberosAlt, TestSolrCloudWithSecureImpersonation, 
TestStressCloudBlindAtomicUpdates, TestStressLiveNodes, 
TestTolerantUpdateProcessorRandomCloud, TriLevelCompositeIdRoutingTest, 
HdfsCollectionsAPIDistributedZkTest, HdfsNNFailoverTest, HdfsRecoverLeaseTest, 
HdfsRecoveryZkTest, HdfsSyncSliceTest, HdfsWriteToMultipleCollectionsTest, 
StressHdfsTest, ZkStateReaderTest, CachingDirectoryFactoryTest, 
ExitableDirectoryReaderTest, HdfsDirectoryFactoryTest, QueryResultKeyTest, 
TestBackupRepositoryFactory, TestConfigOverlay, TestConfigSets, 
TestCorePropertiesReload, TestInfoStreamLogging, TestReloadAndDeleteDocs, 
TestSolrConfigHandler, TestSolrXml, TestSolrCloudSnapshots, TestConfigReload, 
V2ApiIntegrationTest, CoreAdminRequestStatusTest, 
CoreMergeIndexesAdminHandlerTest, MetricsHandlerTest, 
PropertiesRequestHandlerTest, TestApiFramework, TestCollectionAPIs, 
DistributedFacetPivotLongTailTest, ShufflingReplicaListTransformerTest, 
SpatialHeatmapFacetsTest, TestPivotHelperCode, TestTrackingShardHandlerFactory, 
TestSlowCompositeReaderWrapper, WrapperMergePolicyFactoryTest, 
CheckHdfsIndexTest, SolrCoreMetricManagerTest, SolrMetricManagerTest, 
SolrMetricsIntegrationTest, SolrSlf4jReporterTest, SubstringBytesRefFilterTest, 
TestMacros, JSONWriterTest, TestBinaryResponseWriter, TestCustomDocTransformer, 
TestPushWriter, TestSubQueryTransformerCrossCore, 
TestSubQueryTransformerDistrib, TestManagedResource, TestRestManager, 
TestBulkSchemaAPI, TestUniqueKeyFieldResource, BadCopyFieldTest, 
BooleanFieldTest, ChangedSchemaMergeTest, DateRangeFieldTest, 
TestCloudManagedSchema, TestCollationFieldDocValues, 
TestManagedSchemaThreadSafety, TestSchemaManager, TestUseDocValuesAsStored]
   [junit4] Completed [635/711 (1!)] on J0 in 16.65s, 6 tests, 1 error <<< 
FAILURES!

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

Reply via email to