Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/1826/

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([E2EB0B7D383AE673:D0C10CE3C0C4C2AA]:0)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:984)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:931)
        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 13088 lines...]
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/init-core-data-001
   [junit4]   2> 1871743 WARN  
(SUITE-TestUseDocValuesAsStored-seed#[E2EB0B7D383AE673]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 1871743 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[E2EB0B7D383AE673]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 1871745 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[E2EB0B7D383AE673]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 1871747 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testUseDocValuesAsStoredFalse
   [junit4]   2> 1871749 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1871757 WARN  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, 
use <mergePolicyFactory> instead.
   [junit4]   2> 1871758 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1871778 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] 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> 1871781 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1871789 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 1871790 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 1871792 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml
 to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1871826 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42079e8c
   [junit4]   2> 1871831 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42079e8c
   [junit4]   2> 1871831 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42079e8c
   [junit4]   2> 1871842 WARN  (coreLoadExecutor-6579-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 1871843 INFO  (coreLoadExecutor-6579-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1871851 INFO  (coreLoadExecutor-6579-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 1871860 INFO  (coreLoadExecutor-6579-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 1871860 INFO  (coreLoadExecutor-6579-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-001/collection1,
 trusted=true
   [junit4]   2> 1871861 INFO  (coreLoadExecutor-6579-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@42079e8c
   [junit4]   2> 1871861 INFO  (coreLoadExecutor-6579-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1871861 INFO  (coreLoadExecutor-6579-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-001/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/init-core-data-001/]
   [junit4]   2> 1871863 INFO  (coreLoadExecutor-6579-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=26, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=49.150390625, 
floorSegmentMB=2.158203125, forceMergeDeletesPctAllowed=24.881256806913694, 
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1871871 WARN  (coreLoadExecutor-6579-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> 1871919 INFO  (coreLoadExecutor-6579-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1871919 INFO  (coreLoadExecutor-6579-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1871920 INFO  (coreLoadExecutor-6579-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=15, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=68.474609375, 
floorSegmentMB=2.064453125, forceMergeDeletesPctAllowed=11.23103971875109, 
segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1871921 INFO  (coreLoadExecutor-6579-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7f1730b7[collection1] main]
   [junit4]   2> 1871921 INFO  (coreLoadExecutor-6579-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-001/collection1/conf
   [junit4]   2> 1871922 INFO  (coreLoadExecutor-6579-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1871926 INFO  
(searcherExecutor-6580-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7f1730b7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1871927 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1871928 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid]} 0 0
   [junit4]   2> 1871930 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1871930 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@46f80494 commitCommandVersion:0
   [junit4]   2> 1871950 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@dc4d653[collection1] main]
   [junit4]   2> 1871951 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1871952 INFO  (searcherExecutor-6580-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@dc4d653[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1)))}
   [junit4]   2> 1871952 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 21
   [junit4]   2> 1871952 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] 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> 1871953 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] 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> 1871953 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] 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> 1871954 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] 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> 1871954 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1871954 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] 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> 1871954 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1871954 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1043498020
   [junit4]   2> 1871955 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.node, tag=null
   [junit4]   2> 1871956 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jvm, tag=null
   [junit4]   2> 1871958 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jetty, tag=null
   [junit4]   2> 1871958 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1871958 INFO  (coreCloseExecutor-6584-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@587cbb
   [junit4]   2> 1871959 INFO  (coreCloseExecutor-6584-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=5799099
   [junit4]   2> 1871963 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testUseDocValuesAsStoredFalse
   [junit4]   2> 1871965 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testMultipleSearchResults
   [junit4]   2> 1871968 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1871976 WARN  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, 
use <mergePolicyFactory> instead.
   [junit4]   2> 1871977 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1871985 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] 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> 1871987 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1872003 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 1872004 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-002/collection1/conf/managed-schema
   [junit4]   2> 1872007 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml
 to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1872043 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42079e8c
   [junit4]   2> 1872048 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42079e8c
   [junit4]   2> 1872048 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42079e8c
   [junit4]   2> 1872059 WARN  (coreLoadExecutor-6591-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 1872059 INFO  (coreLoadExecutor-6591-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1872068 INFO  (coreLoadExecutor-6591-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 1872077 INFO  (coreLoadExecutor-6591-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 1872077 INFO  (coreLoadExecutor-6591-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-002/collection1,
 trusted=true
   [junit4]   2> 1872078 INFO  (coreLoadExecutor-6591-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@42079e8c
   [junit4]   2> 1872078 INFO  (coreLoadExecutor-6591-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1872078 INFO  (coreLoadExecutor-6591-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-002/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/init-core-data-001/]
   [junit4]   2> 1872082 WARN  (coreLoadExecutor-6591-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> 1872130 INFO  (coreLoadExecutor-6591-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1872130 INFO  (coreLoadExecutor-6591-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1872132 INFO  (coreLoadExecutor-6591-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=26, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=49.150390625, 
floorSegmentMB=2.158203125, forceMergeDeletesPctAllowed=24.881256806913694, 
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1872134 INFO  (coreLoadExecutor-6591-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@5fdf7ede[collection1] main]
   [junit4]   2> 1872134 INFO  (coreLoadExecutor-6591-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-002/collection1/conf
   [junit4]   2> 1872135 INFO  (coreLoadExecutor-6591-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1872136 INFO  
(searcherExecutor-6592-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5fdf7ede[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1)))}
   [junit4]   2> 1872137 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1872138 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid1]} 0 1
   [junit4]   2> 1872138 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid2]} 0 0
   [junit4]   2> 1872139 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid3]} 0 0
   [junit4]   2> 1872139 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid4]} 0 0
   [junit4]   2> 1872140 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid5]} 0 0
   [junit4]   2> 1872140 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid6]} 0 0
   [junit4]   2> 1872140 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1872140 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@2c366e2e commitCommandVersion:0
   [junit4]   2> 1872169 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@460b3885[collection1] main]
   [junit4]   2> 1872170 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1872171 INFO  (searcherExecutor-6592-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@460b3885[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1)
 Uninverting(_1(7.0.0):C6)))}
   [junit4]   2> 1872171 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 30
   [junit4]   2> 1872172 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 1872174 ERROR 
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.SolrTestCaseJ4 query failed JSON validation. error=mismatch: 
'myid1'!='myid' @ response/docs/[0]/id
   [junit4]   2>  expected 
=/response/docs==[{'id':'myid1','test_is_dvo':[101,102,103]},{'id':'myid2','test_is_dvo':[201,202]},{'id':'myid3','test_is_dvo':[301,302,303,304]},{'id':'myid4','test_s_dvo':'hello','test_is_dvo':[401,402]},{'id':'myid5'},{'id':'myid6','test_s_dvo':'hello'}]
   [junit4]   2>  response = {
   [junit4]   2>   "responseHeader":{
   [junit4]   2>     "status":0,
   [junit4]   2>     "QTime":0},
   [junit4]   2>   "response":{"numFound":7,"start":0,"docs":[
   [junit4]   2>       {
   [junit4]   2>         "id":"myid"},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid1",
   [junit4]   2>         "test_is_dvo":[101,
   [junit4]   2>           102,
   [junit4]   2>           103]},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid2",
   [junit4]   2>         "test_is_dvo":[201,
   [junit4]   2>           202]},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid3",
   [junit4]   2>         "test_is_dvo":[301,
   [junit4]   2>           302,
   [junit4]   2>           303,
   [junit4]   2>           304]},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid4",
   [junit4]   2>         "test_s_dvo":"hello",
   [junit4]   2>         "test_is_dvo":[401,
   [junit4]   2>           402]},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid5"},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid6",
   [junit4]   2>         "test_s_dvo":"hello"}]
   [junit4]   2>   }}
   [junit4]   2> 
   [junit4]   2>  request = q=id:myid*&fl=*&wt=xml
   [junit4]   2> 1872174 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1872175 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872175 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1872175 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1803786069
   [junit4]   2> 1872175 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.node, tag=null
   [junit4]   2> 1872176 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jvm, tag=null
   [junit4]   2> 1872178 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jetty, tag=null
   [junit4]   2> 1872178 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1872178 INFO  (coreCloseExecutor-6596-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@24b69826
   [junit4]   2> 1872179 INFO  (coreCloseExecutor-6596-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=615946278
   [junit4]   2> 1872182 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testMultipleSearchResults
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestUseDocValuesAsStored -Dtests.method=testMultipleSearchResults 
-Dtests.seed=E2EB0B7D383AE673 -Dtests.multiplier=2 -Dtests.slow=true 
-Dtests.locale=sr -Dtests.timezone=Africa/Gaborone -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.22s J1 | 
TestUseDocValuesAsStored.testMultipleSearchResults <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: mismatch: 
'myid1'!='myid' @ response/docs/[0]/id
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([E2EB0B7D383AE673:D0C10CE3C0C4C2AA]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:984)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:931)
   [junit4]    >        at 
org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults(TestUseDocValuesAsStored.java:243)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 1872186 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testOnEmptyIndex
   [junit4]   2> 1872189 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1872197 WARN  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 1872198 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1872206 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
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> 1872208 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1872217 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 1872218 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-003/collection1/conf/managed-schema
   [junit4]   2> 1872222 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed 
the non-managed schema 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml
 to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1872255 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42079e8c
   [junit4]   2> 1872260 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42079e8c
   [junit4]   2> 1872260 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42079e8c
   [junit4]   2> 1872272 WARN  (coreLoadExecutor-6603-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 1872273 INFO  (coreLoadExecutor-6603-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1872292 INFO  (coreLoadExecutor-6603-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 1872301 INFO  (coreLoadExecutor-6603-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 1872301 INFO  (coreLoadExecutor-6603-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-003/collection1,
 trusted=true
   [junit4]   2> 1872301 INFO  (coreLoadExecutor-6603-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@42079e8c
   [junit4]   2> 1872301 INFO  (coreLoadExecutor-6603-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1872302 INFO  (coreLoadExecutor-6603-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-003/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/init-core-data-001/]
   [junit4]   2> 1872306 WARN  (coreLoadExecutor-6603-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> 1872381 INFO  (coreLoadExecutor-6603-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1872381 INFO  (coreLoadExecutor-6603-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1872385 INFO  (coreLoadExecutor-6603-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=26, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=49.150390625, 
floorSegmentMB=2.158203125, forceMergeDeletesPctAllowed=24.881256806913694, 
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1872388 INFO  (coreLoadExecutor-6603-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@6a0cd31e[collection1] main]
   [junit4]   2> 1872388 INFO  (coreLoadExecutor-6603-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-003/collection1/conf
   [junit4]   2> 1872389 INFO  (coreLoadExecutor-6603-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1872391 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1872391 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1872392 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
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> 1872392 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1872392 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@a08802f commitCommandVersion:0
   [junit4]   2> 1872394 INFO  
(searcherExecutor-6604-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6a0cd31e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1)
 Uninverting(_1(7.0.0):C6)))}
   [junit4]   2> 1872397 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@708e9476[collection1] main]
   [junit4]   2> 1872397 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1872398 INFO  (searcherExecutor-6604-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@708e9476[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1872401 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 9
   [junit4]   2> 1872402 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 1872402 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1872403 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
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> 1872407 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
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> 1872408 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[xyz]} 0 0
   [junit4]   2> 1872408 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 1872408 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1872409 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
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> 1872409 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
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> 1872409 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1872409 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@a08802f commitCommandVersion:0
   [junit4]   2> 1872431 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@35a45c0c[collection1] main]
   [junit4]   2> 1872432 INFO  (searcherExecutor-6604-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@35a45c0c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):C1)))}
   [junit4]   2> 1872433 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1872433 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 23
   [junit4]   2> 1872433 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 1872434 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1872434 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
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> 1872434 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
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> 1872435 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[xyz]} 0 0
   [junit4]   2> 1872435 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1872435 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@a08802f commitCommandVersion:0
   [junit4]   2> 1872452 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@25d9ea4c[collection1] main]
   [junit4]   2> 1872454 INFO  (searcherExecutor-6604-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@25d9ea4c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(7.0.0):C1)))}
   [junit4]   2> 1872455 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1872455 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 19
   [junit4]   2> 1872455 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 1872456 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1872456 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
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> 1872456 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1872456 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1719953653
   [junit4]   2> 1872456 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1872458 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 1872459 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 1872460 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1872461 INFO  (coreCloseExecutor-6608-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@71459ad3
   [junit4]   2> 1872462 INFO  (coreCloseExecutor-6608-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=1900387027
   [junit4]   2> 1872465 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E2EB0B7D383AE673]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testOnEmptyIndex
   [junit4]   2> 1872467 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued
   [junit4]   2> 1872470 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1872483 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, 
use <mergePolicyFactory> instead.
   [junit4]   2> 1872483 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1872493 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872495 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1872505 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 1872505 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-004/collection1/conf/managed-schema
   [junit4]   2> 1872510 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-004/collection1/conf/schema-non-stored-docvalues.xml
 to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-004/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1872544 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42079e8c
   [junit4]   2> 1872549 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42079e8c
   [junit4]   2> 1872549 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42079e8c
   [junit4]   2> 1872564 WARN  (coreLoadExecutor-6615-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 1872565 INFO  (coreLoadExecutor-6615-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1872574 INFO  (coreLoadExecutor-6615-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 1872582 INFO  (coreLoadExecutor-6615-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 1872582 INFO  (coreLoadExecutor-6615-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-004/collection1,
 trusted=true
   [junit4]   2> 1872583 INFO  (coreLoadExecutor-6615-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@42079e8c
   [junit4]   2> 1872583 INFO  (coreLoadExecutor-6615-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1872583 INFO  (coreLoadExecutor-6615-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-004/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/init-core-data-001/]
   [junit4]   2> 1872587 WARN  (coreLoadExecutor-6615-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> 1872645 INFO  (coreLoadExecutor-6615-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1872645 INFO  (coreLoadExecutor-6615-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1872647 INFO  (coreLoadExecutor-6615-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=26, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=49.150390625, 
floorSegmentMB=2.158203125, forceMergeDeletesPctAllowed=24.881256806913694, 
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1872648 INFO  (coreLoadExecutor-6615-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@42ba7e03[collection1] main]
   [junit4]   2> 1872648 INFO  (coreLoadExecutor-6615-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001/tempDir-004/collection1/conf
   [junit4]   2> 1872649 INFO  (coreLoadExecutor-6615-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1872650 INFO  
(searcherExecutor-6616-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@42ba7e03[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(7.0.0):C1)))}
   [junit4]   2> 1872651 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1872651 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1872652 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872654 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 0
   [junit4]   2> 1872654 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1872654 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@43a9fab9 commitCommandVersion:0
   [junit4]   2> 1872680 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@a419580[collection1] main]
   [junit4]   2> 1872680 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1872681 INFO  (searcherExecutor-6616-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@a419580[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):C1)))}
   [junit4]   2> 1872682 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 27
   [junit4]   2> 1872683 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_ss_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1872684 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_ss_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1872685 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872686 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872687 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_ss_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1872688 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1872690 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 0
   [junit4]   2> 1872690 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1872690 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@43a9fab9 commitCommandVersion:0
   [junit4]   2> 1872717 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2b0768ad[collection1] main]
   [junit4]   2> 1872717 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1872718 INFO  (searcherExecutor-6616-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2b0768ad[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):C1)
 Uninverting(_5(7.0.0):C1)))}
   [junit4]   2> 1872718 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 28
   [junit4]   2> 1872719 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872720 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872720 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872721 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872722 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872722 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 1872725 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 1
   [junit4]   2> 1872725 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1872725 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@43a9fab9 commitCommandVersion:0
   [junit4]   2> 1872751 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4a1ceb5a[collection1] main]
   [junit4]   2> 1872751 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1872752 INFO  (searcherExecutor-6616-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4a1ceb5a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):C1)
 Uninverting(_5(7.0.0):C1) Uninverting(_6(7.0.0):C1)))}
   [junit4]   2> 1872752 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 27
   [junit4]   2> 1872753 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872755 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872756 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872758 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872759 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872760 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 1872763 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[4]} 0 0
   [junit4]   2> 1872763 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1872763 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@43a9fab9 commitCommandVersion:0
   [junit4]   2> 1872784 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@48ddb34f[collection1] main]
   [junit4]   2> 1872784 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1872785 INFO  (searcherExecutor-6616-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@48ddb34f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):C1)
 Uninverting(_5(7.0.0):C1) Uninverting(_6(7.0.0):C1) 
Uninverting(_7(7.0.0):C1)))}
   [junit4]   2> 1872786 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 22
   [junit4]   2> 1872786 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872787 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872789 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872790 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872791 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872792 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 1872795 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 0
   [junit4]   2> 1872795 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1872795 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@43a9fab9 commitCommandVersion:0
   [junit4]   2> 1872816 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@34f37bf8[collection1] main]
   [junit4]   2> 1872817 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1872818 INFO  (searcherExecutor-6616-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@34f37bf8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):C1)
 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> 1872818 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 22
   [junit4]   2> 1872819 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_fs_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1872820 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_fs_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1872822 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872823 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872824 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_fs_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1872825 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1872828 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[6]} 0 0
   [junit4]   2> 1872828 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1872828 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@43a9fab9 commitCommandVersion:0
   [junit4]   2> 1872852 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@679a6586[collection1] main]
   [junit4]   2> 1872853 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1872854 INFO  (searcherExecutor-6616-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@679a6586[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):C1)
 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> 1872854 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 25
   [junit4]   2> 1872855 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=test_dt_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1872855 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=test_dt_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1872856 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872856 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872857 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=test_dt_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1872858 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 1872860 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[7]} 0 0
   [junit4]   2> 1872860 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1872860 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@43a9fab9 commitCommandVersion:0
   [junit4]   2> 1872882 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@52f45dea[collection1] main]
   [junit4]   2> 1872883 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1872884 INFO  (searcherExecutor-6616-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@52f45dea[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):C1)
 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> 1872884 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 24
   [junit4]   2> 1872885 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872886 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872888 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872889 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872890 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872892 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 1872894 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[8]} 0 0
   [junit4]   2> 1872895 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1872895 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@43a9fab9 commitCommandVersion:0
   [junit4]   2> 1872921 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@32b13dd3[collection1] main]
   [junit4]   2> 1872921 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1872922 INFO  (searcherExecutor-6616-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@32b13dd3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):C1)
 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> 1872923 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 27
   [junit4]   2> 1872923 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_ss_dvo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1872925 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_ss_dvo2,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1872927 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=*vo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1872928 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1872930 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_ss_dvo2,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1872932 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
   [junit4]   2> 1872934 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[9]} 0 0
   [junit4]   2> 1872935 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1872935 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@43a9fab9 commitCommandVersion:0
   [junit4]   2> 1872960 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a2340eb[collection1] main]
   [junit4]   2> 1872960 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1872961 INFO  (searcherExecutor-6616-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1a2340eb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):C1)
 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) Uninverting(_c(7.0.0):C1)))}
   [junit4]   2> 1872961 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 26
   [junit4]   2> 1872962 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=enum_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1872963 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=enum_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1872963 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1872964 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1872965 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=enum_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1872965 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=9 status=0 QTime=0
   [junit4]   2> 1872966 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1872967 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] 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> 1872968 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[10]} 0 1
   [junit4]   2> 1872969 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1872969 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@43a9fab9 commitCommandVersion:0
   [junit4]   2> 1873014 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@116a8b50[collection1] main]
   [junit4]   2> 1873014 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1873015 INFO  (searcherExecutor-6616-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@116a8b50[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_d(7.0.0):C1)))}
   [junit4]   2> 1873019 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 50
   [junit4]   2> 1873020 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=test_ss_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1873021 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=test_ss_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1873022 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1873022 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1873038 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=test_ss_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1873039 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1873041 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[11]} 0 0
   [junit4]   2> 1873041 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E2EB0B7D383AE673])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
 

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

DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1885826 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E2EB0B7D383AE673]) [    
] 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> 1885827 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E2EB0B7D383AE673]) [    
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[myid1]} 0 0
   [junit4]   2> 1885828 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E2EB0B7D383AE673]) [    
] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1885828 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E2EB0B7D383AE673]) [    
] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1e706d15 commitCommandVersion:0
   [junit4]   2> 1885856 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E2EB0B7D383AE673]) [    
] o.a.s.s.SolrIndexSearcher Opening [Searcher@24a097c9[collection1] main]
   [junit4]   2> 1885858 INFO  (searcherExecutor-6640-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@24a097c9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_55(7.0.0):C1)))}
   [junit4]   2> 1885858 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E2EB0B7D383AE673]) [    
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1885859 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E2EB0B7D383AE673]) [    
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 31
   [junit4]   2> 1885859 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E2EB0B7D383AE673]) [    
] 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> 1885860 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E2EB0B7D383AE673]) [    
] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1885860 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E2EB0B7D383AE673]) [    
] 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> 1885860 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E2EB0B7D383AE673]) [    
] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1885860 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E2EB0B7D383AE673]) [    
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=682995234
   [junit4]   2> 1885860 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E2EB0B7D383AE673]) [    
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1885862 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E2EB0B7D383AE673]) [    
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 1885863 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E2EB0B7D383AE673]) [    
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 1885863 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E2EB0B7D383AE673]) [    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1885864 INFO  (coreCloseExecutor-6644-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1a7d2a3b
   [junit4]   2> 1885865 INFO  (coreCloseExecutor-6644-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=444410427
   [junit4]   2> 1885872 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E2EB0B7D383AE673]) [    
] o.a.s.SolrTestCaseJ4 ###Ending testManagedSchema
   [junit4]   2> 1885873 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[E2EB0B7D383AE673]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_E2EB0B7D383AE673-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {}, 
docValues:{}, maxPointsInLeafNode=1246, maxMBSortInHeap=5.303774887644203, 
sim=RandomSimilarity(queryNorm=true): {}, locale=sr, timezone=Africa/Gaborone
   [junit4]   2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 
1.8.0_121 (64-bit)/cpus=4,threads=1,free=167079984,total=533200896
   [junit4]   2> NOTE: All tests run in this JVM: [AlternateDirectoryTest, 
SharedFSAutoReplicaFailoverTest, RecoveryAfterSoftCommitTest, 
TestSubQueryTransformer, ZkControllerTest, 
TestDistributedStatsComponentCardinality, DistributedFacetPivotLargeTest, 
DistributedFacetPivotSmallTest, SortByFunctionTest, CollectionsAPISolrJTest, 
GraphQueryTest, TestPerFieldSimilarity, DirectSolrConnectionTest, 
SoftAutoCommitTest, TestConfigSetsAPI, TestCursorMarkWithoutUniqueKey, 
HdfsSyncSliceTest, LargeFieldTest, TestSolrFieldCacheBean, SimplePostToolTest, 
HdfsRecoverLeaseTest, OnlyLeaderIndexesTest, WordBreakSolrSpellCheckerTest, 
TestScoreJoinQPNoScore, TestInPlaceUpdatesStandalone, 
TestSchemalessBufferedUpdates, ParsingFieldUpdateProcessorsTest, 
UpdateRequestProcessorFactoryTest, TestFieldResource, 
TestTrackingShardHandlerFactory, TestQueryTypes, ScriptEngineTest, 
BasicFunctionalityTest, TestExceedMaxTermLength, CloudMLTQParserTest, 
TestSha256AuthenticationProvider, SpatialHeatmapFacetsTest, FullHLLTest, 
TestComplexPhraseLeadingWildcard, CoreAdminRequestStatusTest, 
TestPayloadScoreQParserPlugin, QueryParsingTest, CdcrBootstrapTest, 
AnalysisErrorHandlingTest, SolrSlf4jReporterTest, ChaosMonkeySafeLeaderTest, 
TestAuthorizationFramework, TestReplicaProperties, CollectionStateFormat2Test, 
TestReloadDeadlock, TestWordDelimiterFilterFactory, 
CollectionsAPIDistributedZkTest, MoreLikeThisHandlerTest, TestXIncludeConfig, 
TestPostingsSolrHighlighter, WrapperMergePolicyFactoryTest, 
TestDistributedGrouping, LeaderFailoverAfterPartitionTest, DocumentBuilderTest, 
TestFieldCacheSort, TestTrie, LeaderInitiatedRecoveryOnShardRestartTest, 
TestNumericTerms64, AnalyticsMergeStrategyTest, BlobRepositoryCloudTest, 
TestCryptoKeys, TermsComponentTest, SampleTest, IndexSchemaTest, 
BasicAuthIntegrationTest, SpellCheckCollatorTest, SliceStateTest, 
ClusterStateUpdateTest, TestMaxScoreQueryParser, SOLR749Test, 
TestJavabinTupleStreamParser, ZkSolrClientTest, TestDownShardTolerantSearch, 
DistributedMLTComponentTest, DOMUtilTest, TestUpdate, 
TestDynamicFieldCollectionResource, TestSolrQueryParserResource, 
SolrInfoBeanTest, ConnectionManagerTest, TestQueryUtils, 
CloneFieldUpdateProcessorFactoryTest, TestStressRecovery, TestBulkSchemaAPI, 
TestSurroundQueryParser, ZkCLITest, TestConfigReload, TestFiltering, 
TestExactSharedStatsCache, CSVRequestHandlerTest, SuggesterFSTTest, 
TestMacroExpander, TestCloudJSONFacetJoinDomain, TestSmileRequest, 
TestElisionMultitermQuery, BadComponentTest, TestSearchPerf, 
SchemaVersionSpecificBehaviorTest, UUIDUpdateProcessorFallbackTest, 
TestSweetSpotSimilarityFactory, OverseerStatusTest, TestBM25SimilarityFactory, 
DistributedQueueTest, SolrGangliaReporterTest, TestRawTransformer, 
SolrCLIZkUtilsTest, TestSolrCoreSnapshots, TestOrdValues, 
TestSolrConfigHandler, TestExtendedDismaxParser, TestFoldingMultitermQuery, 
TestStressLucene, PolyFieldTest, TestPseudoReturnFields, QueryEqualityTest, 
TestRemoteStreaming, DocValuesMultiTest, DistanceFunctionTest, 
IndexBasedSpellCheckerTest, DirectSolrSpellCheckerTest, PrimitiveFieldTypeTest, 
TermVectorComponentTest, TestSolrQueryParser, LoggingHandlerTest, 
ReturnFieldsTest, TestAnalyzedSuggestions, TestComponentsName, TestLFUCache, 
ActionThrottleTest, AssignTest, CdcrRequestHandlerTest, 
CdcrVersionReplicationTest, CollectionTooManyReplicasTest, 
DistributedVersionInfoTest, MigrateRouteKeyTest, MoveReplicaTest, 
OverseerTaskQueueTest, TestCloudRecovery, TestLeaderInitiatedRecoveryThread, 
TestMiniSolrCloudClusterSSL, TestRebalanceLeaders, 
TestSolrCloudWithDelegationTokens, TestSolrCloudWithSecureImpersonation, 
TestTolerantUpdateProcessorRandomCloud, HdfsRestartWhileUpdatingTest, 
HdfsThreadLeakTest, HdfsUnloadDistributedZkTest, 
HdfsWriteToMultipleCollectionsTest, StressHdfsTest, TestImplicitCoreProperties, 
TestInfoStreamLogging, TestInitParams, TestSimpleTextCodec, TestSolrXml, 
TestReplicationHandlerBackup, TestReqParamsAPI, TestSQLHandlerNonCloud, 
TestSystemCollAutoCreate, SuggestComponentContextFilterQueryTest, 
HighlighterMaxOffsetTest, TestUnifiedSolrHighlighter, JvmMetricsTest, 
MetricsConfigTest, SolrMetricManagerTest, SolrMetricsIntegrationTest, 
SolrJmxReporterTest, SolrCloudReportersTest, SubstringBytesRefFilterTest, 
JSONWriterTest, TestBinaryResponseWriter, TestSubQueryTransformerCrossCore, 
TestManagedResource, TestClassNameShortening, TestCopyFieldCollectionResource, 
TestDynamicFieldResource, TestFieldCollectionResource, TestSchemaNameResource, 
TestSchemaResource, TestSchemaSimilarityResource, TestSchemaVersionResource, 
TestSerializedLuceneMatchVersion, TestSolrQueryParserDefaultOperatorResource, 
TestUniqueKeyFieldResource, TestManagedStopFilterFactory, 
ChangedSchemaMergeTest, DateRangeFieldTest, EnumFieldTest, 
ManagedSchemaRoundRobinCloudTest, TestCollationFieldDocValues, 
TestHalfAndHalfDocValues, TestManagedSchema, TestManagedSchemaAPI, 
TestManagedSchemaThreadSafety, TestSchemaManager, TestUseDocValuesAsStored]
   [junit4] Completed [641/710 (1!)] on J1 in 14.15s, 6 tests, 1 error <<< 
FAILURES!

[...truncated 43568 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to