[JENKINS] Lucene-Solr-NightlyTests-master - Build # 1345 - Unstable

[Apache Jenkins Server]

Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/1345/

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

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

Stack Trace:
java.lang.RuntimeException: mismatch: 'myid1'!='myid3' @ response/docs/[0]/id
        at 
__randomizedtesting.SeedInfo.seed([DD14891C7B505624:EF3E8E8283AE72FD]:0)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:966)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:913)
        at 
org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults(TestUseDocValuesAsStored.java:243)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 13503 lines...]
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/init-core-data-001
   [junit4]   2> 4880717 WARN  
(SUITE-TestUseDocValuesAsStored-seed#[DD14891C7B505624]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 4880718 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[DD14891C7B505624]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 4880719 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[DD14891C7B505624]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 4880723 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.SolrTestCaseJ4 ###Starting testManagedSchema
   [junit4]   2> 4880726 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 4880770 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 4880779 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] 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> 4880781 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 4880788 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 4880788 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 4880798 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed 
the non-managed schema 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml
 to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 4880872 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a9f5185
   [junit4]   2> 4880877 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a9f5185
   [junit4]   2> 4880878 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a9f5185
   [junit4]   2> 4880908 INFO  (coreLoadExecutor-18402-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 4880931 INFO  (coreLoadExecutor-18402-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 4880938 INFO  (coreLoadExecutor-18402-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 4880938 INFO  (coreLoadExecutor-18402-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/tempDir-001/collection1,
 trusted=true
   [junit4]   2> 4880938 INFO  (coreLoadExecutor-18402-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7a9f5185
   [junit4]   2> 4880938 INFO  (coreLoadExecutor-18402-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 4880938 INFO  (coreLoadExecutor-18402-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/tempDir-001/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/init-core-data-001/]
   [junit4]   2> 4880941 INFO  (coreLoadExecutor-18402-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.MockRandomMergePolicy: 
org.apache.lucene.index.MockRandomMergePolicy@4b00cd5a
   [junit4]   2> 4880969 WARN  (coreLoadExecutor-18402-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler 
{type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, registerPath=/solr,/v2, 
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 4881044 INFO  (coreLoadExecutor-18402-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4881044 INFO  (coreLoadExecutor-18402-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4881046 INFO  (coreLoadExecutor-18402-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=379269927, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 4881046 INFO  (coreLoadExecutor-18402-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7ddaa64a[collection1] main]
   [junit4]   2> 4881047 INFO  (coreLoadExecutor-18402-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/tempDir-001/collection1/conf
   [junit4]   2> 4881048 INFO  (coreLoadExecutor-18402-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 4881049 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 4881050 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 4881051 INFO  
(searcherExecutor-18403-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7ddaa64a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4881071 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 4881080 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 4881080 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] 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> 4881081 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[myid1]} 0 0
   [junit4]   2> 4881083 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4881083 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@46757ddb commitCommandVersion:0
   [junit4]   2> 4881149 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.s.SolrIndexSearcher Opening [Searcher@4b0bdfb9[collection1] main]
   [junit4]   2> 4881150 INFO  (searcherExecutor-18403-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4b0bdfb9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c1)))}
   [junit4]   2> 4881150 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4881150 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 67
   [junit4]   2> 4881152 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid*&fl=*&wt=xml} hits=1 status=0 QTime=1
   [junit4]   2> 4881181 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 4881181 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] 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 28
   [junit4]   2> 4881181 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4881181 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@46757ddb commitCommandVersion:0
   [junit4]   2> 4881207 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.s.SolrIndexSearcher Opening [Searcher@488432f1[collection1] main]
   [junit4]   2> 4881209 INFO  (searcherExecutor-18403-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@488432f1[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4881209 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4881210 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 28
   [junit4]   2> 4881210 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 4881210 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1690789497
   [junit4]   2> 4881210 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 4881212 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 4881213 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 4881213 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 4881215 INFO  (coreCloseExecutor-18407-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@aaa6c10
   [junit4]   2> 4881216 INFO  (coreCloseExecutor-18407-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=178940944
   [junit4]   2> 4881220 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[DD14891C7B505624]) [    
] o.a.s.SolrTestCaseJ4 ###Ending testManagedSchema
   [junit4]   2> 4881222 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testMultipleSearchResults
   [junit4]   2> 4881225 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 4881254 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 4881263 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] 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> 4881265 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 4881272 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 4881273 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/tempDir-002/collection1/conf/managed-schema
   [junit4]   2> 4881276 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml
 to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 4881327 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a9f5185
   [junit4]   2> 4881338 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a9f5185
   [junit4]   2> 4881339 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a9f5185
   [junit4]   2> 4881351 INFO  (coreLoadExecutor-18414-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 4881360 INFO  (coreLoadExecutor-18414-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 4881367 INFO  (coreLoadExecutor-18414-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 4881368 INFO  (coreLoadExecutor-18414-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/tempDir-002/collection1,
 trusted=true
   [junit4]   2> 4881368 INFO  (coreLoadExecutor-18414-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7a9f5185
   [junit4]   2> 4881368 INFO  (coreLoadExecutor-18414-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 4881368 INFO  (coreLoadExecutor-18414-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/tempDir-002/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/init-core-data-001/]
   [junit4]   2> 4881372 WARN  (coreLoadExecutor-18414-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler 
{type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, registerPath=/solr,/v2, 
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 4881487 INFO  (coreLoadExecutor-18414-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4881487 INFO  (coreLoadExecutor-18414-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4881489 INFO  (coreLoadExecutor-18414-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.MockRandomMergePolicy: 
org.apache.lucene.index.MockRandomMergePolicy@6a36a5ba
   [junit4]   2> 4881490 INFO  (coreLoadExecutor-18414-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4d5aa130[collection1] main]
   [junit4]   2> 4881500 INFO  (coreLoadExecutor-18414-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/tempDir-002/collection1/conf
   [junit4]   2> 4881504 INFO  (coreLoadExecutor-18414-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 4881506 INFO  
(searcherExecutor-18415-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4d5aa130[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4881512 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 4881513 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid1]} 0 0
   [junit4]   2> 4881517 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid2]} 0 3
   [junit4]   2> 4881518 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid3]} 0 0
   [junit4]   2> 4881532 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid4]} 0 14
   [junit4]   2> 4881533 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid5]} 0 0
   [junit4]   2> 4881546 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid6]} 0 13
   [junit4]   2> 4881547 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4881547 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@697398b1 commitCommandVersion:0
   [junit4]   2> 4881744 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1fbc0f3c[collection1] main]
   [junit4]   2> 4881744 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4881746 INFO  (searcherExecutor-18415-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1fbc0f3c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(8.0.0):c6)))}
   [junit4]   2> 4881746 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 199
   [junit4]   2> 4881747 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 4881749 ERROR 
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.SolrTestCaseJ4 query failed JSON validation. error=mismatch: 
'myid1'!='myid3' @ response/docs/[0]/id
   [junit4]   2>  expected 
=/response/docs==[{'id':'myid1','test_is_dvo':[101,102,103]},{'id':'myid2','test_is_dvo':[201,202]},{'id':'myid3','test_is_dvo':[301,302,303,304]},{'id':'myid4','test_s_dvo':'hello','test_is_dvo':[401,402]},{'id':'myid5'},{'id':'myid6','test_s_dvo':'hello'}]
   [junit4]   2>  response = {
   [junit4]   2>   "responseHeader":{
   [junit4]   2>     "status":0,
   [junit4]   2>     "QTime":0},
   [junit4]   2>   "response":{"numFound":6,"start":0,"docs":[
   [junit4]   2>       {
   [junit4]   2>         "id":"myid3",
   [junit4]   2>         "test_is_dvo":[301,
   [junit4]   2>           302,
   [junit4]   2>           303,
   [junit4]   2>           304]},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid4",
   [junit4]   2>         "test_s_dvo":"hello",
   [junit4]   2>         "test_is_dvo":[401,
   [junit4]   2>           402]},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid5"},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid6",
   [junit4]   2>         "test_s_dvo":"hello"},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid1",
   [junit4]   2>         "test_is_dvo":[101,
   [junit4]   2>           102,
   [junit4]   2>           103]},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid2",
   [junit4]   2>         "test_is_dvo":[201,
   [junit4]   2>           202]}]
   [junit4]   2>   }}
   [junit4]   2> 
   [junit4]   2>  request = q=id:myid*&fl=*&wt=xml
   [junit4]   2> 4881750 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 4881750 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] 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> 4881750 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4881750 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@697398b1 commitCommandVersion:0
   [junit4]   2> 4881831 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@764984ab[collection1] main]
   [junit4]   2> 4881832 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4881833 INFO  (searcherExecutor-18415-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@764984ab[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4881833 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 83
   [junit4]   2> 4881834 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 4881834 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1516764335
   [junit4]   2> 4881834 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.node, tag=null
   [junit4]   2> 4881836 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jvm, tag=null
   [junit4]   2> 4881863 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jetty, tag=null
   [junit4]   2> 4881864 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 4881864 INFO  (coreCloseExecutor-18419-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@36a72b56
   [junit4]   2> 4881865 INFO  (coreCloseExecutor-18419-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=916925270
   [junit4]   2> 4881869 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DD14891C7B505624])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testMultipleSearchResults
   [junit4]   2> NOTE: download the large Jenkins line-docs file by running 
'ant get-jenkins-line-docs' in the lucene directory.
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestUseDocValuesAsStored -Dtests.method=testMultipleSearchResults 
-Dtests.seed=DD14891C7B505624 -Dtests.multiplier=2 -Dtests.nightly=true 
-Dtests.slow=true 
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt
 -Dtests.locale=hi -Dtests.timezone=Indian/Mahe -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.65s J1 | 
TestUseDocValuesAsStored.testMultipleSearchResults <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: mismatch: 
'myid1'!='myid3' @ response/docs/[0]/id
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([DD14891C7B505624:EF3E8E8283AE72FD]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:966)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:913)
   [junit4]    >        at 
org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults(TestUseDocValuesAsStored.java:243)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 4881880 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued
   [junit4]   2> 4881882 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 4881893 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 4881901 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4881903 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 4881928 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 4881929 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/tempDir-003/collection1/conf/managed-schema
   [junit4]   2> 4881939 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml
 to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 4882004 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a9f5185
   [junit4]   2> 4882009 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a9f5185
   [junit4]   2> 4882010 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a9f5185
   [junit4]   2> 4882022 INFO  (coreLoadExecutor-18426-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 4882031 INFO  (coreLoadExecutor-18426-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 4882058 INFO  (coreLoadExecutor-18426-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 4882058 INFO  (coreLoadExecutor-18426-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/tempDir-003/collection1,
 trusted=true
   [junit4]   2> 4882059 INFO  (coreLoadExecutor-18426-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7a9f5185
   [junit4]   2> 4882059 INFO  (coreLoadExecutor-18426-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 4882059 INFO  (coreLoadExecutor-18426-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/tempDir-003/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/init-core-data-001/]
   [junit4]   2> 4882063 WARN  (coreLoadExecutor-18426-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler 
{type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, registerPath=/solr,/v2, 
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 4882154 INFO  (coreLoadExecutor-18426-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4882155 INFO  (coreLoadExecutor-18426-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4882156 INFO  (coreLoadExecutor-18426-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.MockRandomMergePolicy: 
org.apache.lucene.index.MockRandomMergePolicy@34fee8fa
   [junit4]   2> 4882162 INFO  (coreLoadExecutor-18426-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@45ec980d[collection1] main]
   [junit4]   2> 4882163 INFO  (coreLoadExecutor-18426-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001/tempDir-003/collection1/conf
   [junit4]   2> 4882164 INFO  (coreLoadExecutor-18426-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 4882165 INFO  
(searcherExecutor-18427-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@45ec980d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4882166 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 4882167 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 4882167 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882168 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 0
   [junit4]   2> 4882169 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4882169 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6d267cab commitCommandVersion:0
   [junit4]   2> 4882217 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2fe5ff1a[collection1] main]
   [junit4]   2> 4882217 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4882218 INFO  (searcherExecutor-18427-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2fe5ff1a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(8.0.0):c1)))}
   [junit4]   2> 4882218 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 49
   [junit4]   2> 4882219 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_ss_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4882227 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_ss_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4882229 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882235 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882237 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_ss_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4882267 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4882269 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 0
   [junit4]   2> 4882269 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4882269 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6d267cab commitCommandVersion:0
   [junit4]   2> 4882335 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@f5ffc98[collection1] main]
   [junit4]   2> 4882335 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4882337 INFO  (searcherExecutor-18427-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@f5ffc98[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(8.0.0):c1)
 Uninverting(_6(8.0.0):c1)))}
   [junit4]   2> 4882337 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 67
   [junit4]   2> 4882337 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882338 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882339 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882339 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882340 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882340 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 4882342 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 0
   [junit4]   2> 4882342 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4882342 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6d267cab commitCommandVersion:0
   [junit4]   2> 4882416 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7ffb08e9[collection1] main]
   [junit4]   2> 4882416 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4882420 INFO  (searcherExecutor-18427-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7ffb08e9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_8(8.0.0):c3)))}
   [junit4]   2> 4882433 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 91
   [junit4]   2> 4882434 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882443 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882444 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882445 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882445 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882446 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 4882447 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[4]} 0 0
   [junit4]   2> 4882448 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4882448 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6d267cab commitCommandVersion:0
   [junit4]   2> 4882528 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@43c6c9c8[collection1] main]
   [junit4]   2> 4882528 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4882529 INFO  (searcherExecutor-18427-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@43c6c9c8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_8(8.0.0):c3)
 Uninverting(_9(8.0.0):c1)))}
   [junit4]   2> 4882530 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 82
   [junit4]   2> 4882530 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_l_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4882531 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_l_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4882532 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882532 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882533 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_l_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4882533 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 4882535 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 1
   [junit4]   2> 4882536 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4882536 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6d267cab commitCommandVersion:0
   [junit4]   2> 4882569 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@76915466[collection1] main]
   [junit4]   2> 4882570 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4882571 INFO  (searcherExecutor-18427-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@76915466[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_8(8.0.0):c3)
 Uninverting(_9(8.0.0):c1) Uninverting(_a(8.0.0):c1)))}
   [junit4]   2> 4882571 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 35
   [junit4]   2> 4882572 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882572 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882589 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=*dvo&wt=xml} hits=1 status=0 QTime=9
   [junit4]   2> 4882590 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882590 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882591 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 4882593 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[6]} 0 0
   [junit4]   2> 4882593 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4882593 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6d267cab commitCommandVersion:0
   [junit4]   2> 4882623 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@50d3c3ce[collection1] main]
   [junit4]   2> 4882625 INFO  (searcherExecutor-18427-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@50d3c3ce[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_8(8.0.0):c3)
 Uninverting(_9(8.0.0):c1) Uninverting(_a(8.0.0):c1) 
Uninverting(_b(8.0.0):c1)))}
   [junit4]   2> 4882625 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4882625 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 32
   [junit4]   2> 4882626 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=test_dts_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4882627 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=test_dts_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4882628 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882653 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882654 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=test_dts_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4882655 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 4882657 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[7]} 0 0
   [junit4]   2> 4882657 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4882657 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6d267cab commitCommandVersion:0
   [junit4]   2> 4882707 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@172196f8[collection1] main]
   [junit4]   2> 4882708 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4882709 INFO  (searcherExecutor-18427-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@172196f8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_8(8.0.0):c3)
 Uninverting(_9(8.0.0):c1) Uninverting(_a(8.0.0):c1) Uninverting(_b(8.0.0):c1) 
Uninverting(_c(8.0.0):c1)))}
   [junit4]   2> 4882709 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 52
   [junit4]   2> 4882710 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_s_dv&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4882710 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_s_dv,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4882711 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882712 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882712 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_s_dv,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4882713 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 4882714 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[8]} 0 0
   [junit4]   2> 4882714 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4882714 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6d267cab commitCommandVersion:0
   [junit4]   2> 4882762 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@58266762[collection1] main]
   [junit4]   2> 4882762 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4882763 INFO  (searcherExecutor-18427-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@58266762[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_8(8.0.0):c3)
 Uninverting(_9(8.0.0):c1) Uninverting(_a(8.0.0):c1) Uninverting(_b(8.0.0):c1) 
Uninverting(_c(8.0.0):c1) Uninverting(_d(8.0.0):c1)))}
   [junit4]   2> 4882763 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 49
   [junit4]   2> 4882764 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_s_dvo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4882765 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_s_dvo2,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4882765 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882766 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882767 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_s_dvo2,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4882767 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
   [junit4]   2> 4882770 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[9]} 0 0
   [junit4]   2> 4882770 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4882770 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6d267cab commitCommandVersion:0
   [junit4]   2> 4882819 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4d4b366a[collection1] main]
   [junit4]   2> 4882820 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4882821 INFO  (searcherExecutor-18427-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4d4b366a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_8(8.0.0):c3)
 Uninverting(_9(8.0.0):c1) Uninverting(_a(8.0.0):c1) Uninverting(_b(8.0.0):c1) 
Uninverting(_c(8.0.0):c1) Uninverting(_d(8.0.0):c1) Uninverting(_e(8.0.0):c1)))}
   [junit4]   2> 4882821 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 51
   [junit4]   2> 4882822 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882824 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882826 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882836 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882838 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882850 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=9 status=0 QTime=0
   [junit4]   2> 4882852 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 4882852 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882853 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[10]} 0 0
   [junit4]   2> 4882854 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4882854 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6d267cab commitCommandVersion:0
   [junit4]   2> 4882918 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@c6579fb[collection1] main]
   [junit4]   2> 4882918 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4882920 INFO  (searcherExecutor-18427-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@c6579fb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_f(8.0.0):c1)))}
   [junit4]   2> 4882924 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 70
   [junit4]   2> 4882925 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882925 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882926 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882926 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882927 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882927 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4882928 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[11]} 0 0
   [junit4]   2> 4882929 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4882929 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6d267cab commitCommandVersion:0
   [junit4]   2> 4882969 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2413717d[collection1] main]
   [junit4]   2> 4882970 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4882972 INFO  (searcherExecutor-18427-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2413717d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_h(8.0.0):c2)))}
   [junit4]   2> 4882975 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 46
   [junit4]   2> 4882976 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882976 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882977 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882977 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882978 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4882978 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 4882980 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[12]} 0 0
   [junit4]   2> 4882980 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4882980 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6d267cab commitCommandVersion:0
   [junit4]   2> 4883141 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@36b17b0c[collection1] main]
   [junit4]   2> 4883141 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4883142 INFO  (searcherExecutor-18427-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@36b17b0c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_h(8.0.0):c2)
 Uninverting(_i(8.0.0):c1)))}
   [junit4]   2> 4883147 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 167
   [junit4]   2> 4883148 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4883149 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4883150 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4883154 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4883163 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4883164 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 4883166 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[13]} 0 1
   [junit4]   2> 4883167 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4883167 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6d267cab commitCommandVersion:0
   [junit4]   2> 4883190 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@69be5216[collection1] main]
   [junit4]   2> 4883191 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4883192 INFO  (searcherExecutor-18427-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@69be5216[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_h(8.0.0):c2)
 Uninverting(_i(8.0.0):c1) Uninverting(_j(8.0.0):c1)))}
   [junit4]   2> 4883192 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 25
   [junit4]   2> 4883193 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4883193 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4883194 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4883194 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4883195 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4883196 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 4883197 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[14]} 0 0
   [junit4]   2> 4883198 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4883198 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6d267cab commitCommandVersion:0
   [junit4]   2> 4883247 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3c48d9bf[collection1] main]
   [junit4]   2> 4883248 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4883249 INFO  (searcherExecutor-18427-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3c48d9bf[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_h(8.0.0):c2)
 Uninverting(_i(8.0.0):c1) Uninverting(_j(8.0.0):c1) 
Uninverting(_k(8.0.0):c1)))}
   [junit4]   2> 4883249 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 51
   [junit4]   2> 4883249 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4883251 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4883253 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4883254 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4883255 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4883261 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 4883263 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[15]} 0 0
   [junit4]   2> 4883264 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4883264 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6d267cab commitCommandVersion:0
   [junit4]   2> 4883330 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@28df9dfe[collection1] main]
   [junit4]   2> 4883331 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4883332 INFO  (searcherExecutor-18427-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@28df9dfe[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_h(8.0.0):c2)
 Uninverting(_i(8.0.0):c1) Uninverting(_j(8.0.0):c1) Uninverting(_k(8.0.0):c1) 
Uninverting(_l(8.0.0):c1)))}
   [junit4]   2> 4883339 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 75
   [junit4]   2> 4883340 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4883341 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4883350 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4883351 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4883353 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] 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> 4883354 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 4883357 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[16]} 0 0
   [junit4]   2> 4883357 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4883357 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6d267cab commitCommandVersion:0
   [junit4]   2> 4883408 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DD14891C7B505624])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5ca5c23

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

o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 4897199 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DD14891C7B505624]) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jvm, tag=null
   [junit4]   2> 4897200 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DD14891C7B505624]) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jetty, tag=null
   [junit4]   2> 4897201 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DD14891C7B505624]) 
[    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 4897202 INFO  (coreCloseExecutor-18467-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@68119c92
   [junit4]   2> 4897204 INFO  (coreCloseExecutor-18467-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=1745984658
   [junit4]   2> 4897213 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DD14891C7B505624]) 
[    ] o.a.s.SolrTestCaseJ4 ###Ending testMultivaluedOrdering
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_DD14891C7B505624-001
   [junit4]   2> NOTE: test params are: 
codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST,
 chunkSize=32661, maxDocsPerChunk=203, blockSize=7), 
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, 
chunkSize=32661, blockSize=7)), sim=RandomSimilarity(queryNorm=true): {}, 
locale=hi, timezone=Indian/Mahe
   [junit4]   2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 
1.8.0_131 (64-bit)/cpus=4,threads=1,free=193367600,total=532152320
   [junit4]   2> NOTE: All tests run in this JVM: 
[LeaderInitiatedRecoveryOnCommitTest, DisMaxRequestHandlerTest, 
TestConfigSetProperties, DOMUtilTest, TestManagedSynonymFilterFactory, 
TestNoOpRegenerator, TestTestInjection, 
OverseerCollectionConfigSetProcessorTest, TestReloadAndDeleteDocs, 
DirectSolrConnectionTest, DistributedFacetPivotLongTailTest, GraphQueryTest, 
TestCloudJSONFacetJoinDomain, CloudMLTQParserTest, TestClusterStateMutator, 
TestDynamicFieldResource, BasicAuthIntegrationTest, FileBasedSpellCheckerTest, 
HdfsBasicDistributedZk2Test, TestSerializedLuceneMatchVersion, 
ImplicitSnitchTest, TestFieldCacheSort, TestLRUCache, TestNumericTokenStream, 
TestFiltering, LeaderElectionContextKeyTest, IndexSchemaRuntimeFieldTest, 
TestCryptoKeys, TestAuthorizationFramework, SchemaVersionSpecificBehaviorTest, 
SolrMetricReporterTest, TestComplexPhraseLeadingWildcard, 
RecoveryAfterSoftCommitTest, TestBinaryResponseWriter, TestSSLRandomization, 
TestUnifiedSolrHighlighter, TestHalfAndHalfDocValues, BufferStoreTest, 
CollectionsAPIAsyncDistributedZkTest, TestCoreContainer, 
TestDownShardTolerantSearch, TestDFISimilarityFactory, TestTrieFacet, 
TestPartialUpdateDeduplication, TestManagedResourceStorage, 
SolrGraphiteReporterTest, TestBM25SimilarityFactory, DeleteStatusTest, 
TestJsonFacetRefinement, TestTolerantUpdateProcessorRandomCloud, 
ResourceLoaderTest, SpellCheckComponentTest, TestExactSharedStatsCache, 
TestBackupRepositoryFactory, TestQueryTypes, TestSweetSpotSimilarityFactory, 
SolrCLIZkUtilsTest, TestScoreJoinQPNoScore, ExitableDirectoryReaderTest, 
TermVectorComponentTest, DocValuesTest, BooleanFieldTest, 
BigEndianAscendingWordDeserializerTest, TestSolrIndexConfig, TestSolrJ, 
TestSha256AuthenticationProvider, ExplicitHLLTest, 
TestHighFrequencyDictionaryFactory, CollectionReloadTest, 
AsyncCallRequestStatusResponseTest, DocValuesNotIndexedTest, SOLR749Test, 
TestConfigSetsAPI, LoggingHandlerTest, TestMacros, TestReloadDeadlock, 
TestFieldSortValues, BlobRepositoryCloudTest, CustomCollectionTest, 
TestCursorMarkWithoutUniqueKey, TestFastLRUCache, SortSpecParsingTest, 
ScriptEngineTest, TestCustomStream, TestOrdValues, 
TestSimpleTrackingShardHandler, TestSQLHandler, HdfsRecoverLeaseTest, 
TestInPlaceUpdatesStandalone, ConjunctionSolrSpellCheckerTest, LargeFieldTest, 
ClusterStateUpdateTest, TestSchemalessBufferedUpdates, 
StatelessScriptUpdateProcessorFactoryTest, DistributedExpandComponentTest, 
TestMaxScoreQueryParser, TestPrepRecovery, 
SuggestComponentContextFilterQueryTest, ZkSolrClientTest, 
TestMultiValuedNumericRangeQuery, WrapperMergePolicyFactoryTest, 
SolrCmdDistributorTest, DocValuesMultiTest, TestReplicaProperties, 
TestBlobHandler, SharedFSAutoReplicaFailoverTest, 
TestSolrConfigHandlerConcurrent, CacheHeaderTest, TestUpdate, 
DistributedSuggestComponentTest, BlockJoinFacetDistribTest, 
TestFieldTypeCollectionResource, TestDocTermOrds, TestCloudRecovery, 
TestExceedMaxTermLength, MissingSegmentRecoveryTest, 
TestAuthenticationFramework, MetricsConfigTest, TestSchemaResource, 
DistribJoinFromCollectionTest, ChaosMonkeySafeLeaderTest, 
BasicDistributedZk2Test, CollectionsAPIDistributedZkTest, 
OpenCloseCoreStressTest, LeaderElectionIntegrationTest, ShardRoutingTest, 
BasicZkTest, FullSolrCloudDistribCmdsTest, TestRandomFaceting, 
LeaderElectionTest, TestZkChroot, ZkCLITest, ShardRoutingCustomTest, 
TestDistributedSearch, AutoCommitTest, TestDistributedGrouping, 
TestHashPartitioner, DistributedSpellCheckComponentTest, 
TermVectorComponentDistributedTest, ZkControllerTest, TestJoin, 
TestStressVersions, DistributedTermsComponentTest, TestRangeQuery, 
StatsComponentTest, TestGroupingSearch, PeerSyncTest, ConvertedLegacyTest, 
TestSort, BasicFunctionalityTest, HighlighterTest, SoftAutoCommitTest, 
ShowFileRequestHandlerTest, SimplePostToolTest, TestCoreDiscovery, 
AnalysisAfterCoreReloadTest, SuggesterFSTTest, CoreAdminHandlerTest, 
SuggesterTSTTest, SpatialFilterTest, SuggesterWFSTTest, TestCSVLoader, 
PolyFieldTest, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest, 
TestPseudoReturnFields, TestAtomicUpdateErrorCases, 
TestWordDelimiterFilterFactory, QueryEqualityTest, 
DefaultValueUpdateProcessorTest, SortByFunctionTest, TestRemoteStreaming, 
DistanceFunctionTest, TestSolrDeletionPolicy1, DebugComponentTest, 
IndexBasedSpellCheckerTest, LukeRequestHandlerTest, TestWriterPerf, 
DirectSolrSpellCheckerTest, DocumentAnalysisRequestHandlerTest, 
RequestHandlersTest, TestValueSourceCache, TestIndexingPerformance, 
MoreLikeThisHandlerTest, FieldAnalysisRequestHandlerTest, SolrPluginUtilsTest, 
IndexSchemaTest, TestJmxIntegration, TestCollationField, 
UpdateRequestProcessorFactoryTest, MBeansHandlerTest, QueryParsingTest, 
TestAnalyzedSuggestions, UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest, 
BinaryUpdateRequestHandlerTest, PingRequestHandlerTest, CSVRequestHandlerTest, 
SearchHandlerTest, TestLFUCache, HighlighterConfigTest, 
TestQuerySenderListener, UpdateParamsTest, AlternateDirectoryTest, 
TestQuerySenderNoQuery, ResponseLogComponentTest, BadComponentTest, 
TestStressRecovery, TestMergePolicyConfig, SampleTest, TestDocSet, 
TestBinaryField, TestSearchPerf, NumericFieldsTest, MinimalSchemaTest, 
TestElisionMultitermQuery, OutputWriterTest, TestFuzzyAnalyzedSuggestions, 
ExternalFileFieldSortTest, TestPostingsSolrHighlighter, 
NotRequiredUniqueKeyTest, TestLuceneMatchVersion, TestCharFilters, 
TestXIncludeConfig, EchoParamsTest, TestPerFieldSimilarity, 
TestIBSimilarityFactory, TimeZoneUtilsTest, TestFastOutputStream, 
PreAnalyzedFieldTest, TestSystemIdResolver, PrimUtilsTest, 
TestSuggestSpellingConverter, DateFieldTest, SpellingQueryConverterTest, 
TestDocumentBuilder, ZkNodePropsTest, SliceStateTest, SystemInfoHandlerTest, 
FileUtilsTest, CircularListTest, DistributedMLTComponentTest, TestRTGBase, 
CursorPagingTest, DistributedIntervalFacetingTest, SolrTestCaseJ4Test, 
TestEmbeddedSolrServerSchemaAPI, ConnectionReuseTest, ActionThrottleTest, 
AliasIntegrationTest, AssignTest, BaseCdcrDistributedZkTest, 
CdcrReplicationDistributedZkTest, CdcrReplicationHandlerTest, 
CdcrVersionReplicationTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, 
CloudExitableDirectoryReaderTest, CollectionStateFormat2Test, 
ConcurrentDeleteAndCreateCollectionTest, ForceLeaderTest, SolrCloudExampleTest, 
TestCloudDeleteByQuery, TestCloudPivotFacet, TestConfigSetsAPIExclusivity, 
TestDeleteCollectionOnDownNodes, TestDistribDocBasedVersion, 
TestExclusionRuleCollectionAccess, TestHdfsCloudBackupRestore, 
TestLeaderElectionWithEmptyReplica, TestLeaderInitiatedRecoveryThread, 
TestLocalFSCloudBackupRestore, TestLockTree, TestMiniSolrCloudCluster, 
TestPullReplica, TestPullReplicaErrorHandling, TestRequestForwarding, 
TestSegmentSorting, TestSizeLimitedDistributedMap, TestStressLiveNodes, 
TlogReplayBufferedWhileIndexingTest, HdfsWriteToMultipleCollectionsTest, 
ZkStateWriterTest, RuleEngineTest, ConfigureRecoveryStrategyTest, 
DirectoryFactoryTest, HdfsDirectoryFactoryTest, QueryResultKeyTest, 
TestDynamicLoading, TestSolrCoreSnapshots, TestRestoreCore, V2StandaloneTest, 
CoreAdminCreateDiscoverTest, CoreAdminRequestStatusTest, 
CoreMergeIndexesAdminHandlerTest, MetricsHandlerTest, 
SegmentsInfoRequestHandlerTest, TestApiFramework, TestCollectionAPIs, 
TestConfigsApi, DistributedFacetPivotSmallTest, 
DistributedFacetPivotWhiteBoxTest, InfixSuggestersTest, TestExpandComponent, 
TestHttpShardHandlerFactory, TestTrackingShardHandlerFactory, 
SolrCloudReportersTest, TestMacroExpander, JSONWriterTest, SmileWriterTest, 
TestExportWriter, TestSolrQueryResponse, TestSubQueryTransformerCrossCore, 
TestRestManager, TestClassNameShortening, TestDynamicFieldCollectionResource, 
TestFieldCollectionResource, TestManagedStopFilterFactory, 
TestManagedSynonymGraphFilterFactory, BadCopyFieldTest, DateRangeFieldTest, 
DocValuesMissingTest, EnumFieldTest, ManagedSchemaRoundRobinCloudTest, 
TestManagedSchemaAPI, TestManagedSchemaThreadSafety, TestSchemaManager, 
TestUseDocValuesAsStored]
   [junit4] Completed [651/725 (1!)] on J1 in 16.54s, 6 tests, 1 error <<< 
FAILURES!

[...truncated 43448 lines...]

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