Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/19360/
Java: 64bit/jdk1.8.0_121 -XX:-UseCompressedOops -XX:+UseSerialGC

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

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

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




Build Log:
[...truncated 11768 lines...]
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/init-core-data-001
   [junit4]   2> 785980 WARN  
(SUITE-TestUseDocValuesAsStored-seed#[15383C501E77A116]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 785980 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[15383C501E77A116]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 785981 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[15383C501E77A116]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 785982 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.SolrTestCaseJ4 ###Starting testMultivaluedOrdering
   [junit4]   2> 785996 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 786006 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 786011 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] 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> 786014 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 786021 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 786036 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 786070 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 786090 WARN  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using 
first MBeanServer.
   [junit4]   2> 786092 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@320baeca
   [junit4]   2> 786092 WARN  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using 
first MBeanServer.
   [junit4]   2> 786095 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@320baeca
   [junit4]   2> 786095 WARN  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using 
first MBeanServer.
   [junit4]   2> 786095 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@320baeca
   [junit4]   2> 786104 INFO  (coreLoadExecutor-4006-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 786119 INFO  (coreLoadExecutor-4006-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 786126 INFO  (coreLoadExecutor-4006-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 786126 INFO  (coreLoadExecutor-4006-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-001/collection1,
 trusted=true
   [junit4]   2> 786126 WARN  (coreLoadExecutor-4006-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 786126 INFO  (coreLoadExecutor-4006-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@320baeca
   [junit4]   2> 786127 INFO  (coreLoadExecutor-4006-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 786127 INFO  (coreLoadExecutor-4006-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-001/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/init-core-data-001/]
   [junit4]   2> 786128 INFO  (coreLoadExecutor-4006-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=36, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 786150 WARN  (coreLoadExecutor-4006-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> 786183 INFO  (coreLoadExecutor-4006-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 786183 INFO  (coreLoadExecutor-4006-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 786185 INFO  (coreLoadExecutor-4006-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1550077906, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 786185 INFO  (coreLoadExecutor-4006-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7399b67c[collection1] main]
   [junit4]   2> 786186 INFO  (coreLoadExecutor-4006-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-001/collection1/conf
   [junit4]   2> 786186 INFO  (coreLoadExecutor-4006-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 786186 INFO  
(searcherExecutor-4007-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7399b67c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 786187 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 786188 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 786188 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] 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> 786188 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[1]} 0 0
   [junit4]   2> 786191 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[2]} 0 0
   [junit4]   2> 786192 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[3]} 0 0
   [junit4]   2> 786193 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[4]} 0 1
   [junit4]   2> 786193 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 786193 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@13af2c72 commitCommandVersion:0
   [junit4]   2> 786251 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a954bd8[collection1] main]
   [junit4]   2> 786252 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 786252 INFO  (searcherExecutor-4007-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1a954bd8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c4)))}
   [junit4]   2> 786252 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 58
   [junit4]   2> 786253 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_mvt_dvt_st_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 786254 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_mvt_dvt_sf_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 786255 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_mvt_dvf_st_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 786256 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_mvt_dvu_st_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 786256 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 786256 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1298358989
   [junit4]   2> 786256 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.node, tag=null
   [junit4]   2> 786257 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 786258 INFO  (coreCloseExecutor-4011-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1a669bf6
   [junit4]   2> 786260 INFO  (coreCloseExecutor-4011-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=442932214
   [junit4]   2> 786262 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[15383C501E77A116]) 
[    ] o.a.s.SolrTestCaseJ4 ###Ending testMultivaluedOrdering
   [junit4]   2> 786264 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testUseDocValuesAsStoredFalse
   [junit4]   2> 786266 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 786273 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 786279 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] 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> 786282 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 786295 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 786295 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-002/collection1/conf/managed-schema
   [junit4]   2> 786321 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 786341 WARN  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 786344 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@320baeca
   [junit4]   2> 786344 WARN  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 786346 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@320baeca
   [junit4]   2> 786346 WARN  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 786347 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@320baeca
   [junit4]   2> 786355 INFO  (coreLoadExecutor-4018-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 786362 INFO  (coreLoadExecutor-4018-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 786368 INFO  (coreLoadExecutor-4018-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 786368 INFO  (coreLoadExecutor-4018-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-002/collection1,
 trusted=true
   [junit4]   2> 786368 WARN  (coreLoadExecutor-4018-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 786368 INFO  (coreLoadExecutor-4018-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@320baeca
   [junit4]   2> 786368 INFO  (coreLoadExecutor-4018-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 786369 INFO  (coreLoadExecutor-4018-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-002/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/init-core-data-001/]
   [junit4]   2> 786371 WARN  (coreLoadExecutor-4018-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> 786399 INFO  (coreLoadExecutor-4018-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 786399 INFO  (coreLoadExecutor-4018-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 786400 INFO  (coreLoadExecutor-4018-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=36, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 786401 INFO  (coreLoadExecutor-4018-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@61629414[collection1] main]
   [junit4]   2> 786401 INFO  (coreLoadExecutor-4018-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-002/collection1/conf
   [junit4]   2> 786401 INFO  (coreLoadExecutor-4018-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 786401 INFO  
(searcherExecutor-4019-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@61629414[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c4)))}
   [junit4]   2> 786403 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 786404 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid]} 0 0
   [junit4]   2> 786404 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 786404 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@2d247a43 commitCommandVersion:0
   [junit4]   2> 786482 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@203076a2[collection1] main]
   [junit4]   2> 786482 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 786482 INFO  (searcherExecutor-4019-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@203076a2[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c4)
 Uninverting(_1(7.0.0):c1)))}
   [junit4]   2> 786482 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 78
   [junit4]   2> 786483 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 786483 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 786483 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid&fl=id,nonstored_dv_*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 786484 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid&fl=id,nonstored_dv_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 786484 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 786484 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=265153762
   [junit4]   2> 786484 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.node, tag=null
   [junit4]   2> 786485 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 786485 INFO  (coreCloseExecutor-4023-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@4a9d6fa2
   [junit4]   2> 786486 INFO  (coreCloseExecutor-4023-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=1251831714
   [junit4]   2> 786488 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[15383C501E77A116])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testUseDocValuesAsStoredFalse
   [junit4]   2> 786489 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testMultipleSearchResults
   [junit4]   2> 786491 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 786506 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 786512 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] 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> 786514 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 786524 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 786525 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-003/collection1/conf/managed-schema
   [junit4]   2> 786536 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 786556 WARN  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 786559 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@320baeca
   [junit4]   2> 786559 WARN  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 786561 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@320baeca
   [junit4]   2> 786561 WARN  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 786561 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@320baeca
   [junit4]   2> 786570 INFO  (coreLoadExecutor-4030-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 786577 INFO  (coreLoadExecutor-4030-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 786590 INFO  (coreLoadExecutor-4030-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 786590 INFO  (coreLoadExecutor-4030-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-003/collection1,
 trusted=true
   [junit4]   2> 786591 WARN  (coreLoadExecutor-4030-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 786591 INFO  (coreLoadExecutor-4030-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@320baeca
   [junit4]   2> 786591 INFO  (coreLoadExecutor-4030-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 786591 INFO  (coreLoadExecutor-4030-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-003/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/init-core-data-001/]
   [junit4]   2> 786593 WARN  (coreLoadExecutor-4030-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> 786620 INFO  (coreLoadExecutor-4030-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 786620 INFO  (coreLoadExecutor-4030-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 786622 INFO  (coreLoadExecutor-4030-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=36, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 786623 INFO  (coreLoadExecutor-4030-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1f4029aa[collection1] main]
   [junit4]   2> 786623 INFO  (coreLoadExecutor-4030-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-003/collection1/conf
   [junit4]   2> 786624 INFO  (coreLoadExecutor-4030-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 786624 INFO  
(searcherExecutor-4031-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1f4029aa[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c4)
 Uninverting(_1(7.0.0):c1)))}
   [junit4]   2> 786625 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 786626 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid1]} 0 0
   [junit4]   2> 786627 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid2]} 0 0
   [junit4]   2> 786627 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid3]} 0 0
   [junit4]   2> 786627 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid4]} 0 0
   [junit4]   2> 786628 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid5]} 0 0
   [junit4]   2> 786628 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid6]} 0 0
   [junit4]   2> 786628 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 786628 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7a189f66 commitCommandVersion:0
   [junit4]   2> 786674 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@69cbc30[collection1] main]
   [junit4]   2> 786674 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 786674 INFO  (searcherExecutor-4031-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@69cbc30[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c4)
 Uninverting(_1(7.0.0):c1) Uninverting(_2(7.0.0):c6)))}
   [junit4]   2> 786674 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 46
   [junit4]   2> 786682 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid*&fl=*&wt=xml} hits=7 status=0 QTime=7
   [junit4]   2> 786683 ERROR 
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.SolrTestCaseJ4 query failed JSON validation. error=mismatch: 
'myid1'!='myid' @ response/docs/[0]/id
   [junit4]   2>  expected 
=/response/docs==[{'id':'myid1','test_is_dvo':[101,102,103]},{'id':'myid2','test_is_dvo':[201,202]},{'id':'myid3','test_is_dvo':[301,302,303,304]},{'id':'myid4','test_s_dvo':'hello','test_is_dvo':[401,402]},{'id':'myid5'},{'id':'myid6','test_s_dvo':'hello'}]
   [junit4]   2>  response = {
   [junit4]   2>   "responseHeader":{
   [junit4]   2>     "status":0,
   [junit4]   2>     "QTime":7},
   [junit4]   2>   "response":{"numFound":7,"start":0,"docs":[
   [junit4]   2>       {
   [junit4]   2>         "id":"myid"},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid1",
   [junit4]   2>         "test_is_dvo":[101,
   [junit4]   2>           102,
   [junit4]   2>           103]},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid2",
   [junit4]   2>         "test_is_dvo":[201,
   [junit4]   2>           202]},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid3",
   [junit4]   2>         "test_is_dvo":[301,
   [junit4]   2>           302,
   [junit4]   2>           303,
   [junit4]   2>           304]},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid4",
   [junit4]   2>         "test_s_dvo":"hello",
   [junit4]   2>         "test_is_dvo":[401,
   [junit4]   2>           402]},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid5"},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid6",
   [junit4]   2>         "test_s_dvo":"hello"}]
   [junit4]   2>   }}
   [junit4]   2> 
   [junit4]   2>  request = q=id:myid*&fl=*&wt=xml
   [junit4]   2> 786684 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 786684 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1563093133
   [junit4]   2> 786684 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.node, tag=null
   [junit4]   2> 786685 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 786686 INFO  (coreCloseExecutor-4035-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@69cb8094
   [junit4]   2> 786686 INFO  (coreCloseExecutor-4035-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=1774944404
   [junit4]   2> 786688 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[15383C501E77A116])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testMultipleSearchResults
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestUseDocValuesAsStored -Dtests.method=testMultipleSearchResults 
-Dtests.seed=15383C501E77A116 -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=sr-Latn -Dtests.timezone=GMT -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.20s J0 | 
TestUseDocValuesAsStored.testMultipleSearchResults <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: mismatch: 
'myid1'!='myid' @ response/docs/[0]/id
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([15383C501E77A116:27123BCEE68985CF]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:970)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:917)
   [junit4]    >        at 
org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults(TestUseDocValuesAsStored.java:243)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 786692 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testOnEmptyIndex
   [junit4]   2> 786695 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 786704 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 786711 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
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> 786712 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 786719 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 786719 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-004/collection1/conf/managed-schema
   [junit4]   2> 786732 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed 
the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-004/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-004/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 786759 WARN  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first 
MBeanServer.
   [junit4]   2> 786761 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@320baeca
   [junit4]   2> 786761 WARN  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first 
MBeanServer.
   [junit4]   2> 786764 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@320baeca
   [junit4]   2> 786764 WARN  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first 
MBeanServer.
   [junit4]   2> 786764 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@320baeca
   [junit4]   2> 786775 INFO  (coreLoadExecutor-4042-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 786785 INFO  (coreLoadExecutor-4042-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 786793 INFO  (coreLoadExecutor-4042-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 786793 INFO  (coreLoadExecutor-4042-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-004/collection1,
 trusted=true
   [junit4]   2> 786793 WARN  (coreLoadExecutor-4042-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 786793 INFO  (coreLoadExecutor-4042-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@320baeca
   [junit4]   2> 786793 INFO  (coreLoadExecutor-4042-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 786793 INFO  (coreLoadExecutor-4042-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-004/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/init-core-data-001/]
   [junit4]   2> 786795 WARN  (coreLoadExecutor-4042-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> 786822 INFO  (coreLoadExecutor-4042-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 786822 INFO  (coreLoadExecutor-4042-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 786823 INFO  (coreLoadExecutor-4042-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=36, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 786825 INFO  (coreLoadExecutor-4042-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@540a0f03[collection1] main]
   [junit4]   2> 786825 INFO  (coreLoadExecutor-4042-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-004/collection1/conf
   [junit4]   2> 786826 INFO  (coreLoadExecutor-4042-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 786826 INFO  
(searcherExecutor-4043-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@540a0f03[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c4)
 Uninverting(_1(7.0.0):c1) Uninverting(_2(7.0.0):c6)))}
   [junit4]   2> 786828 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 786828 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 786829 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
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> 786829 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 786829 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@386dabdf commitCommandVersion:0
   [junit4]   2> 786857 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@265e6e88[collection1] main]
   [junit4]   2> 786858 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 786858 INFO  (searcherExecutor-4043-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@265e6e88[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 786858 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 29
   [junit4]   2> 786859 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 786859 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 786859 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 786860 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*,test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 786861 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[xyz]} 0 0
   [junit4]   2> 786861 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 786861 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 786861 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 786862 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*,test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 786862 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 786862 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@386dabdf commitCommandVersion:0
   [junit4]   2> 786912 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@637122b4[collection1] main]
   [junit4]   2> 786913 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 786913 INFO  (searcherExecutor-4043-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@637122b4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(7.0.0):c1)))}
   [junit4]   2> 786913 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 51
   [junit4]   2> 786913 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 786914 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 786914 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=test_nonstored_dv_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 786914 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*,test_nonstored_dv_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 786915 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[xyz]} 0 0
   [junit4]   2> 786916 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 786916 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@386dabdf commitCommandVersion:0
   [junit4]   2> 787009 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@611ea405[collection1] main]
   [junit4]   2> 787009 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 787009 INFO  (searcherExecutor-4043-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@611ea405[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):c1)))}
   [junit4]   2> 787010 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 94
   [junit4]   2> 787010 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 787010 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 787010 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=2076697736
   [junit4]   2> 787011 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 787011 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 787012 INFO  (coreCloseExecutor-4047-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@2a9b6c70
   [junit4]   2> 787012 INFO  (coreCloseExecutor-4047-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=714828912
   [junit4]   2> 787014 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[15383C501E77A116]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testOnEmptyIndex
   [junit4]   2> 787016 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued
   [junit4]   2> 787017 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 787026 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 787039 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 787040 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 787048 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 787048 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-005/collection1/conf/managed-schema
   [junit4]   2> 787066 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-005/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-005/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 787085 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 787088 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@320baeca
   [junit4]   2> 787088 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 787091 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@320baeca
   [junit4]   2> 787091 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 787091 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@320baeca
   [junit4]   2> 787101 INFO  (coreLoadExecutor-4054-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 787108 INFO  (coreLoadExecutor-4054-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 787114 INFO  (coreLoadExecutor-4054-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 787114 INFO  (coreLoadExecutor-4054-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-005/collection1,
 trusted=true
   [junit4]   2> 787115 WARN  (coreLoadExecutor-4054-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 787115 INFO  (coreLoadExecutor-4054-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@320baeca
   [junit4]   2> 787115 INFO  (coreLoadExecutor-4054-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 787115 INFO  (coreLoadExecutor-4054-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-005/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/init-core-data-001/]
   [junit4]   2> 787118 WARN  (coreLoadExecutor-4054-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> 787152 INFO  (coreLoadExecutor-4054-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 787152 INFO  (coreLoadExecutor-4054-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 787153 INFO  (coreLoadExecutor-4054-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=36, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 787154 INFO  (coreLoadExecutor-4054-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1d5584bf[collection1] main]
   [junit4]   2> 787155 INFO  (coreLoadExecutor-4054-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-005/collection1/conf
   [junit4]   2> 787155 INFO  (coreLoadExecutor-4054-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 787155 INFO  
(searcherExecutor-4055-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1d5584bf[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):c1)))}
   [junit4]   2> 787156 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 787156 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 787157 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 787158 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 0
   [junit4]   2> 787158 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 787158 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@487c799d commitCommandVersion:0
   [junit4]   2> 787299 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4b1accf5[collection1] main]
   [junit4]   2> 787299 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 787299 INFO  (searcherExecutor-4055-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4b1accf5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(7.0.0):c1)))}
   [junit4]   2> 787299 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 141
   [junit4]   2> 787300 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_s_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 787300 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_s_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 787301 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 787301 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 787302 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_s_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 787302 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 787303 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 0
   [junit4]   2> 787303 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 787303 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@487c799d commitCommandVersion:0
   [junit4]   2> 787431 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5db86d37[collection1] main]
   [junit4]   2> 787432 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 787432 INFO  (searcherExecutor-4055-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5db86d37[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(7.0.0):c1)
 Uninverting(_6(7.0.0):c1)))}
   [junit4]   2> 787432 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 128
   [junit4]   2> 787432 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 787433 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 787434 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 787434 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 787435 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 787435 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 787437 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 1
   [junit4]   2> 787438 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 787438 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@487c799d commitCommandVersion:0
   [junit4]   2> 787573 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3362926a[collection1] main]
   [junit4]   2> 787573 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 787573 INFO  (searcherExecutor-4055-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3362926a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(7.0.0):c1)
 Uninverting(_6(7.0.0):c1) Uninverting(_7(7.0.0):c1)))}
   [junit4]   2> 787573 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 135
   [junit4]   2> 787574 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_ds_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 787575 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_ds_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 787576 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 787577 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 787578 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_ds_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 787579 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 787581 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[4]} 0 0
   [junit4]   2> 787581 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 787582 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@487c799d commitCommandVersion:0
   [junit4]   2> 787734 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@67ce6f3f[collection1] main]
   [junit4]   2> 787735 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 787735 INFO  (searcherExecutor-4055-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@67ce6f3f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(7.0.0):c1)
 Uninverting(_6(7.0.0):c1) Uninverting(_7(7.0.0):c1) 
Uninverting(_8(7.0.0):c1)))}
   [junit4]   2> 787735 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 153
   [junit4]   2> 787735 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_ls_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 787737 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_ls_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 787737 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 787738 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 787739 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_ls_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 787740 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 787742 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 1
   [junit4]   2> 787743 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 787743 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@487c799d commitCommandVersion:0
   [junit4]   2> 787840 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@656fff1d[collection1] main]
   [junit4]   2> 787840 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 787840 INFO  (searcherExecutor-4055-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@656fff1d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(7.0.0):c1)
 Uninverting(_6(7.0.0):c1) Uninverting(_7(7.0.0):c1) Uninverting(_8(7.0.0):c1) 
Uninverting(_9(7.0.0):c1)))}
   [junit4]   2> 787840 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 97
   [junit4]   2> 787841 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 787842 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 787842 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 787843 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 787843 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 787844 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 787846 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[6]} 0 1
   [junit4]   2> 787846 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 787846 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@487c799d commitCommandVersion:0
   [junit4]   2> 787993 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@42c74aa8[collection1] main]
   [junit4]   2> 787993 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 787993 INFO  (searcherExecutor-4055-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@42c74aa8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(7.0.0):c1)
 Uninverting(_6(7.0.0):c1) Uninverting(_7(7.0.0):c1) Uninverting(_8(7.0.0):c1) 
Uninverting(_9(7.0.0):c1) Uninverting(_a(7.0.0):c1)))}
   [junit4]   2> 787993 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 146
   [junit4]   2> 787994 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 787994 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 787995 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 787996 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 787996 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 787997 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 787999 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[7]} 0 0
   [junit4]   2> 787999 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 788000 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@487c799d commitCommandVersion:0
   [junit4]   2> 788157 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1cf58d94[collection1] main]
   [junit4]   2> 788157 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 788157 INFO  (searcherExecutor-4055-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1cf58d94[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(7.0.0):c1)
 Uninverting(_6(7.0.0):c1) Uninverting(_7(7.0.0):c1) Uninverting(_8(7.0.0):c1) 
Uninverting(_9(7.0.0):c1) Uninverting(_a(7.0.0):c1) Uninverting(_b(7.0.0):c1)))}
   [junit4]   2> 788157 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 158
   [junit4]   2> 788158 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_ss_dv&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 788159 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_ss_dv,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 788161 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 788161 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] 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> 788162 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_ss_dv,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 788163 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 788165 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[8]} 0 0
   [junit4]   2> 788165 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[15383C501E77A116])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 788165 INFO  (TEST-TestUseD

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

1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using 
first MBeanServer.
   [junit4]   2> 811843 INFO  (coreLoadExecutor-4066-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@320baeca
   [junit4]   2> 811843 INFO  (coreLoadExecutor-4066-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 811843 INFO  (coreLoadExecutor-4066-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-006/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/init-core-data-001/]
   [junit4]   2> 811845 WARN  (coreLoadExecutor-4066-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> 811872 INFO  (coreLoadExecutor-4066-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 811872 INFO  (coreLoadExecutor-4066-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 811873 INFO  (coreLoadExecutor-4066-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=36, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 811877 INFO  (coreLoadExecutor-4066-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@29c137db[collection1] main]
   [junit4]   2> 811877 INFO  (coreLoadExecutor-4066-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-006/collection1/conf
   [junit4]   2> 811877 INFO  (coreLoadExecutor-4066-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 811878 INFO  
(searcherExecutor-4067-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@29c137db[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7e(7.0.0):c1)
 Uninverting(_7f(7.0.0):c1) Uninverting(_7g(7.0.0):c1) 
Uninverting(_7h(7.0.0):c1) Uninverting(_7i(7.0.0):c1) 
Uninverting(_7j(7.0.0):c1) Uninverting(_7k(7.0.0):c1) 
Uninverting(_7l(7.0.0):c1) Uninverting(_7m(7.0.0):c1)))}
   [junit4]   2> 811879 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[15383C501E77A116]) [    
] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 811879 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[15383C501E77A116]) [    
] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-006/collection1/conf/managed-schema
   [junit4]   2> 811886 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[15383C501E77A116]) [    
] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001/tempDir-006/collection1/conf/managed-schema
   [junit4]   2> 811891 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[15383C501E77A116]) [    
] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 811891 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[15383C501E77A116]) [    
] 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> 811892 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[15383C501E77A116]) [    
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[myid1]} 0 0
   [junit4]   2> 811892 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[15383C501E77A116]) [    
] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 811892 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[15383C501E77A116]) [    
] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1e585075 commitCommandVersion:0
   [junit4]   2> 811933 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[15383C501E77A116]) [    
] o.a.s.s.SolrIndexSearcher Opening [Searcher@40d6cd97[collection1] main]
   [junit4]   2> 811933 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[15383C501E77A116]) [    
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 811933 INFO  (searcherExecutor-4067-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@40d6cd97[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7n(7.0.0):c1)))}
   [junit4]   2> 811934 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[15383C501E77A116]) [    
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 42
   [junit4]   2> 811935 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[15383C501E77A116]) [    
] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 811935 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[15383C501E77A116]) [    
] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 811935 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[15383C501E77A116]) [    
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1395911924
   [junit4]   2> 811935 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[15383C501E77A116]) [    
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 811936 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[15383C501E77A116]) [    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 811936 INFO  (coreCloseExecutor-4071-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@328ea9f2
   [junit4]   2> 811937 INFO  (coreCloseExecutor-4071-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=848210418
   [junit4]   2> 811945 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[15383C501E77A116]) [    
] o.a.s.SolrTestCaseJ4 ###Ending testManagedSchema
   [junit4]   2> 811946 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[15383C501E77A116]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_15383C501E77A116-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70), 
sim=RandomSimilarity(queryNorm=false): {}, locale=sr-Latn, timezone=GMT
   [junit4]   2> NOTE: Linux 4.4.0-72-generic amd64/Oracle Corporation 
1.8.0_121 (64-bit)/cpus=12,threads=1,free=308665408,total=509607936
   [junit4]   2> NOTE: All tests run in this JVM: [TestSchemaVersionResource, 
TestStressLiveNodes, TestValueSourceCache, TestFunctionQuery, 
TestSolrFieldCacheMBean, CoreAdminRequestStatusTest, SpatialRPTFieldTypeTest, 
MissingSegmentRecoveryTest, TestOmitPositions, TestPseudoReturnFields, 
TestPushWriter, SolrCloudReportersTest, WrapperMergePolicyFactoryTest, 
SolrXmlInZkTest, TestRandomFlRTGCloud, TestBinaryResponseWriter, 
TestSSLRandomization, TestDistribIDF, DistributedDebugComponentTest, 
CdcrReplicationHandlerTest, ZkNodePropsTest, SolrMetricReporterTest, 
TestFreeTextSuggestions, CollectionsAPIAsyncDistributedZkTest, 
TestJmxMonitoredMap, InfixSuggestersTest, TestClassicSimilarityFactory, 
TestOverriddenPrefixQueryForCustomFieldType, TestTestInjection, 
TestSuggestSpellingConverter, AnalysisAfterCoreReloadTest, TestSolr4Spatial, 
TestRangeQuery, ReturnFieldsTest, TestFieldResource, AnalyticsQueryTest, 
AddBlockUpdateTest, CustomCollectionTest, ResponseLogComponentTest, 
TestJmxIntegration, SimpleCollectionCreateDeleteTest, TestReplicationHandler, 
TestBackupRepositoryFactory, DistributedFacetPivotSmallTest, 
TestSystemCollAutoCreate, TestConfigReload, ExitableDirectoryReaderTest, 
UUIDFieldTest, TemplateUpdateProcessorTest, LeaderElectionContextKeyTest, 
JvmMetricsTest, PolyFieldTest, HdfsRecoverLeaseTest, DocValuesNotIndexedTest, 
TestClassNameShortening, DeleteStatusTest, TestCustomStream, 
RollingRestartTest, UUIDUpdateProcessorFallbackTest, TestReqParamsAPI, 
TestCloudRecovery, AliasIntegrationTest, TestReplicaProperties, 
TestPerFieldSimilarityWithDefaultOverride, ResponseHeaderTest, 
XsltUpdateRequestHandlerTest, GraphQueryTest, TestRuleBasedAuthorizationPlugin, 
LeaderInitiatedRecoveryOnCommitTest, CacheHeaderTest, 
TestLeaderElectionWithEmptyReplica, NodeMutatorTest, TestManagedSchema, 
RegexBoostProcessorTest, TestFilteredDocIdSet, TestDFRSimilarityFactory, 
TestUseDocValuesAsStored]
   [junit4] Completed [265/704 (1!)] on J0 in 25.99s, 6 tests, 1 error <<< 
FAILURES!

[...truncated 44803 lines...]

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

Reply via email to