Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/881/

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([7D1C775D54D632E8:4F3670C3AC281631]:0)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:983)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:930)
        at 
org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults(TestUseDocValuesAsStored.java:243)
        at 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 13126 lines...]
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/init-core-data-001
   [junit4]   2> 1860455 WARN  
(SUITE-TestUseDocValuesAsStored-seed#[7D1C775D54D632E8]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 1860455 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[7D1C775D54D632E8]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 1860457 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[7D1C775D54D632E8]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 1860458 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.SolrTestCaseJ4 ###Starting testManagedSchema
   [junit4]   2> 1860461 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1860470 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1860479 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] 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> 1860481 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1860490 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 1860491 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 1860494 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed 
the non-managed schema 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml
 to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1860498 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1860528 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f83e2d4
   [junit4]   2> 1860533 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f83e2d4
   [junit4]   2> 1860533 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f83e2d4
   [junit4]   2> 1860546 INFO  (coreLoadExecutor-9285-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1860556 INFO  (coreLoadExecutor-9285-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 1860565 INFO  (coreLoadExecutor-9285-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 1860566 INFO  (coreLoadExecutor-9285-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-001/collection1,
 trusted=true
   [junit4]   2> 1860566 INFO  (coreLoadExecutor-9285-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@f83e2d4
   [junit4]   2> 1860566 INFO  (coreLoadExecutor-9285-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1860566 INFO  (coreLoadExecutor-9285-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-001/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/init-core-data-001/]
   [junit4]   2> 1860568 INFO  (coreLoadExecutor-9285-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 1860581 WARN  (coreLoadExecutor-9285-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> 1860640 INFO  (coreLoadExecutor-9285-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1860640 INFO  (coreLoadExecutor-9285-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1860642 INFO  (coreLoadExecutor-9285-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=14, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=67.2138671875, 
floorSegmentMB=2.009765625, forceMergeDeletesPctAllowed=14.877633481001169, 
segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1860642 INFO  (coreLoadExecutor-9285-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1b6b762a[collection1] main]
   [junit4]   2> 1860643 INFO  (coreLoadExecutor-9285-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-001/collection1/conf
   [junit4]   2> 1860643 INFO  (coreLoadExecutor-9285-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1860643 INFO  
(searcherExecutor-9286-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1b6b762a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1860644 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1860645 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 1860648 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 1860651 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1860651 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] 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> 1860652 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[myid1]} 0 0
   [junit4]   2> 1860654 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1860654 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@169c5bab
   [junit4]   2> 1860679 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.s.SolrIndexSearcher Opening [Searcher@4a331790[collection1] main]
   [junit4]   2> 1860680 INFO  (searcherExecutor-9286-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4a331790[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1)))}
   [junit4]   2> 1860680 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1860680 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 26
   [junit4]   2> 1860681 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] 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> 1860682 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1860682 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] 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> 1860682 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1860682 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=698353096
   [junit4]   2> 1860683 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1860684 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 1860685 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 1860690 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1860691 INFO  (coreCloseExecutor-9290-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@2400dab1
   [junit4]   2> 1860692 INFO  (coreCloseExecutor-9290-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 1860701 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[7D1C775D54D632E8]) [    
] o.a.s.SolrTestCaseJ4 ###Ending testManagedSchema
   [junit4]   2> 1860703 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.SolrTestCaseJ4 ###Starting testMultivaluedOrdering
   [junit4]   2> 1860705 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1860715 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1860737 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] 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> 1860740 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1860749 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 1860750 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-002/collection1/conf/managed-schema
   [junit4]   2> 1860752 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml
 to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1860763 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1860870 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f83e2d4
   [junit4]   2> 1860876 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f83e2d4
   [junit4]   2> 1860876 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f83e2d4
   [junit4]   2> 1860893 INFO  (coreLoadExecutor-9297-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1860904 INFO  (coreLoadExecutor-9297-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 1860913 INFO  (coreLoadExecutor-9297-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 1860914 INFO  (coreLoadExecutor-9297-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-002/collection1,
 trusted=true
   [junit4]   2> 1860914 INFO  (coreLoadExecutor-9297-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@f83e2d4
   [junit4]   2> 1860914 INFO  (coreLoadExecutor-9297-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1860914 INFO  (coreLoadExecutor-9297-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-002/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/init-core-data-001/]
   [junit4]   2> 1860918 WARN  (coreLoadExecutor-9297-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> 1860966 INFO  (coreLoadExecutor-9297-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1860966 INFO  (coreLoadExecutor-9297-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1860967 INFO  (coreLoadExecutor-9297-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 1860969 INFO  (coreLoadExecutor-9297-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@50102f90[collection1] main]
   [junit4]   2> 1860969 INFO  (coreLoadExecutor-9297-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-002/collection1/conf
   [junit4]   2> 1860969 INFO  (coreLoadExecutor-9297-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1860970 INFO  
(searcherExecutor-9298-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@50102f90[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1)))}
   [junit4]   2> 1860971 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1860971 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1860971 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] 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> 1860972 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[1]} 0 0
   [junit4]   2> 1860973 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[2]} 0 0
   [junit4]   2> 1860973 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[3]} 0 0
   [junit4]   2> 1860973 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[4]} 0 0
   [junit4]   2> 1860974 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1860974 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@41451ec3
   [junit4]   2> 1860985 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1846a0ef[collection1] main]
   [junit4]   2> 1860985 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1860985 INFO  (searcherExecutor-9298-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1846a0ef[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.6.0):c4)))}
   [junit4]   2> 1860986 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 12
   [junit4]   2> 1860986 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_mvt_dvt_st_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1860987 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_mvt_dvt_sf_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1860988 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_mvt_dvf_st_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1860988 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_mvt_dvu_st_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1860989 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1860989 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] 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> 1860989 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1860989 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=517912888
   [junit4]   2> 1860989 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1860990 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 1860992 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 1860992 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1860992 INFO  (coreCloseExecutor-9302-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1ec5ec02
   [junit4]   2> 1860993 INFO  (coreCloseExecutor-9302-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 1860996 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[7D1C775D54D632E8]) 
[    ] o.a.s.SolrTestCaseJ4 ###Ending testMultivaluedOrdering
   [junit4]   2> 1860997 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testUseDocValuesAsStoredFalse
   [junit4]   2> 1861000 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1861010 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1861018 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] 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> 1861021 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1861030 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 1861031 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-003/collection1/conf/managed-schema
   [junit4]   2> 1861033 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml
 to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1861035 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1861068 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f83e2d4
   [junit4]   2> 1861073 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f83e2d4
   [junit4]   2> 1861073 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f83e2d4
   [junit4]   2> 1861104 INFO  (coreLoadExecutor-9309-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1861113 INFO  (coreLoadExecutor-9309-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 1861122 INFO  (coreLoadExecutor-9309-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 1861123 INFO  (coreLoadExecutor-9309-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-003/collection1,
 trusted=true
   [junit4]   2> 1861123 INFO  (coreLoadExecutor-9309-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@f83e2d4
   [junit4]   2> 1861123 INFO  (coreLoadExecutor-9309-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1861123 INFO  (coreLoadExecutor-9309-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-003/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/init-core-data-001/]
   [junit4]   2> 1861127 WARN  (coreLoadExecutor-9309-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> 1861176 INFO  (coreLoadExecutor-9309-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1861176 INFO  (coreLoadExecutor-9309-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1861177 INFO  (coreLoadExecutor-9309-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 1861179 INFO  (coreLoadExecutor-9309-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@5036f7f3[collection1] main]
   [junit4]   2> 1861179 INFO  (coreLoadExecutor-9309-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-003/collection1/conf
   [junit4]   2> 1861180 INFO  (coreLoadExecutor-9309-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1861182 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1861183 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid]} 0 0
   [junit4]   2> 1861183 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1861183 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@44fd04e6
   [junit4]   2> 1861186 INFO  
(searcherExecutor-9310-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5036f7f3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.6.0):c4)))}
   [junit4]   2> 1861194 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@43f8ebdb[collection1] main]
   [junit4]   2> 1861195 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1861195 INFO  (searcherExecutor-9310-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@43f8ebdb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.6.0):c4)
 Uninverting(_2(6.6.0):c1)))}
   [junit4]   2> 1861195 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 11
   [junit4]   2> 1861196 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] 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> 1861196 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] 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> 1861196 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] 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> 1861197 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] 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> 1861197 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1861197 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] 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> 1861197 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1861197 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=496306945
   [junit4]   2> 1861198 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1861199 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 1861200 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 1861200 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1861200 INFO  (coreCloseExecutor-9314-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@14d4ebfc
   [junit4]   2> 1861201 INFO  (coreCloseExecutor-9314-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 1861204 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[7D1C775D54D632E8])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testUseDocValuesAsStoredFalse
   [junit4]   2> 1861206 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testMultipleSearchResults
   [junit4]   2> 1861209 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1861219 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1861228 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] 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> 1861231 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1861242 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 1861243 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-004/collection1/conf/managed-schema
   [junit4]   2> 1861245 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-004/collection1/conf/schema-non-stored-docvalues.xml
 to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-004/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1861253 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1861282 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f83e2d4
   [junit4]   2> 1861287 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f83e2d4
   [junit4]   2> 1861288 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f83e2d4
   [junit4]   2> 1861300 INFO  (coreLoadExecutor-9321-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1861380 INFO  (coreLoadExecutor-9321-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 1861400 INFO  (coreLoadExecutor-9321-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 1861401 INFO  (coreLoadExecutor-9321-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-004/collection1,
 trusted=true
   [junit4]   2> 1861401 INFO  (coreLoadExecutor-9321-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@f83e2d4
   [junit4]   2> 1861401 INFO  (coreLoadExecutor-9321-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1861401 INFO  (coreLoadExecutor-9321-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-004/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/init-core-data-001/]
   [junit4]   2> 1861405 WARN  (coreLoadExecutor-9321-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> 1861452 INFO  (coreLoadExecutor-9321-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1861453 INFO  (coreLoadExecutor-9321-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1861454 INFO  (coreLoadExecutor-9321-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 1861456 INFO  (coreLoadExecutor-9321-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7de4dbdf[collection1] main]
   [junit4]   2> 1861457 INFO  (coreLoadExecutor-9321-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-004/collection1/conf
   [junit4]   2> 1861457 INFO  (coreLoadExecutor-9321-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1861457 INFO  
(searcherExecutor-9322-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7de4dbdf[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.6.0):c4)
 Uninverting(_2(6.6.0):c1)))}
   [junit4]   2> 1861458 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1861459 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid1]} 0 0
   [junit4]   2> 1861460 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid2]} 0 0
   [junit4]   2> 1861460 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid3]} 0 0
   [junit4]   2> 1861460 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid4]} 0 0
   [junit4]   2> 1861461 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid5]} 0 0
   [junit4]   2> 1861461 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid6]} 0 0
   [junit4]   2> 1861461 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1861461 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@16761ae6
   [junit4]   2> 1861477 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@385c1fdd[collection1] main]
   [junit4]   2> 1861478 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1861478 INFO  (searcherExecutor-9322-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@385c1fdd[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.6.0):c4)
 Uninverting(_2(6.6.0):c1) Uninverting(_3(6.6.0):c6)))}
   [junit4]   2> 1861478 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 16
   [junit4]   2> 1861479 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] 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> 1861484 ERROR 
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] 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> 1861485 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1861485 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] 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> 1861485 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1861485 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=273457901
   [junit4]   2> 1861485 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1861486 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 1861487 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 1861488 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1861492 INFO  (coreCloseExecutor-9326-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@7d963c24
   [junit4]   2> 1861497 INFO  (coreCloseExecutor-9326-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 1861506 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[7D1C775D54D632E8])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testMultipleSearchResults
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestUseDocValuesAsStored -Dtests.method=testMultipleSearchResults 
-Dtests.seed=7D1C775D54D632E8 -Dtests.multiplier=2 -Dtests.slow=true 
-Dtests.locale=he-IL -Dtests.timezone=America/Chicago -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.30s J2 | 
TestUseDocValuesAsStored.testMultipleSearchResults <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: mismatch: 
'myid1'!='myid' @ response/docs/[0]/id
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([7D1C775D54D632E8:4F3670C3AC281631]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:983)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:930)
   [junit4]    >        at 
org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults(TestUseDocValuesAsStored.java:243)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 1861510 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued
   [junit4]   2> 1861513 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1861523 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1861531 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861534 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1861547 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 1861548 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-005/collection1/conf/managed-schema
   [junit4]   2> 1861550 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-005/collection1/conf/schema-non-stored-docvalues.xml
 to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-005/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1861552 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1861583 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f83e2d4
   [junit4]   2> 1861588 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f83e2d4
   [junit4]   2> 1861588 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f83e2d4
   [junit4]   2> 1861601 INFO  (coreLoadExecutor-9333-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1861617 INFO  (coreLoadExecutor-9333-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 1861627 INFO  (coreLoadExecutor-9333-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 1861627 INFO  (coreLoadExecutor-9333-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-005/collection1,
 trusted=true
   [junit4]   2> 1861627 INFO  (coreLoadExecutor-9333-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@f83e2d4
   [junit4]   2> 1861627 INFO  (coreLoadExecutor-9333-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1861627 INFO  (coreLoadExecutor-9333-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-005/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/init-core-data-001/]
   [junit4]   2> 1861631 WARN  (coreLoadExecutor-9333-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> 1861679 INFO  (coreLoadExecutor-9333-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1861679 INFO  (coreLoadExecutor-9333-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1861681 INFO  (coreLoadExecutor-9333-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 1861684 INFO  (coreLoadExecutor-9333-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@760aa507[collection1] main]
   [junit4]   2> 1861684 INFO  (coreLoadExecutor-9333-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001/tempDir-005/collection1/conf
   [junit4]   2> 1861684 INFO  (coreLoadExecutor-9333-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1861685 INFO  
(searcherExecutor-9334-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@760aa507[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.6.0):c4)
 Uninverting(_2(6.6.0):c1) Uninverting(_3(6.6.0):c6)))}
   [junit4]   2> 1861686 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1861686 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1861687 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861688 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 0
   [junit4]   2> 1861688 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1861688 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ed2a62d
   [junit4]   2> 1861701 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@510d8165[collection1] main]
   [junit4]   2> 1861702 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1861702 INFO  (searcherExecutor-9334-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@510d8165[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.6.0):c1)))}
   [junit4]   2> 1861703 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 14
   [junit4]   2> 1861704 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861705 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861706 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861707 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861708 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861709 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1861710 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 0
   [junit4]   2> 1861710 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1861711 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ed2a62d
   [junit4]   2> 1861733 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@788d85c7[collection1] main]
   [junit4]   2> 1861734 INFO  (searcherExecutor-9334-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@788d85c7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.6.0):c1)
 Uninverting(_5(6.6.0):c1)))}
   [junit4]   2> 1861734 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1861734 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 23
   [junit4]   2> 1861735 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_is_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1861736 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_is_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1861736 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861737 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861741 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_is_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1861741 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 1861743 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 0
   [junit4]   2> 1861744 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1861744 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ed2a62d
   [junit4]   2> 1861758 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5c1b45d8[collection1] main]
   [junit4]   2> 1861759 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1861759 INFO  (searcherExecutor-9334-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5c1b45d8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.6.0):c1)
 Uninverting(_5(6.6.0):c1) Uninverting(_6(6.6.0):c1)))}
   [junit4]   2> 1861759 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 15
   [junit4]   2> 1861760 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861772 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861774 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861775 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861777 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861785 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 1861787 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[4]} 0 0
   [junit4]   2> 1861788 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1861788 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ed2a62d
   [junit4]   2> 1861798 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@f797921[collection1] main]
   [junit4]   2> 1861799 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1861799 INFO  (searcherExecutor-9334-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@f797921[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.6.0):c1)
 Uninverting(_5(6.6.0):c1) Uninverting(_6(6.6.0):c1) 
Uninverting(_7(6.6.0):c1)))}
   [junit4]   2> 1861800 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 12
   [junit4]   2> 1861801 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861802 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861807 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=*dvo&wt=xml} hits=1 status=0 QTime=4
   [junit4]   2> 1861808 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861809 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861810 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 1861812 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 0
   [junit4]   2> 1861813 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1861813 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ed2a62d
   [junit4]   2> 1861827 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5f7f6f2f[collection1] main]
   [junit4]   2> 1861827 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1861828 INFO  (searcherExecutor-9334-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5f7f6f2f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.6.0):c1)
 Uninverting(_5(6.6.0):c1) Uninverting(_6(6.6.0):c1) Uninverting(_7(6.6.0):c1) 
Uninverting(_8(6.6.0):c1)))}
   [junit4]   2> 1861828 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 15
   [junit4]   2> 1861828 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_f_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1861829 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_f_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1861830 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861837 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861838 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_f_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1861838 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1861840 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[6]} 0 0
   [junit4]   2> 1861840 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1861840 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ed2a62d
   [junit4]   2> 1861857 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@53ab83c7[collection1] main]
   [junit4]   2> 1861858 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1861858 INFO  (searcherExecutor-9334-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@53ab83c7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.6.0):c1)
 Uninverting(_5(6.6.0):c1) Uninverting(_6(6.6.0):c1) Uninverting(_7(6.6.0):c1) 
Uninverting(_8(6.6.0):c1) Uninverting(_9(6.6.0):c1)))}
   [junit4]   2> 1861858 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 17
   [junit4]   2> 1861859 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861859 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861860 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861861 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861861 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861862 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 1861863 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[7]} 0 0
   [junit4]   2> 1861864 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1861864 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ed2a62d
   [junit4]   2> 1861881 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@46bcbff5[collection1] main]
   [junit4]   2> 1861881 INFO  (searcherExecutor-9334-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@46bcbff5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.6.0):c1)
 Uninverting(_5(6.6.0):c1) Uninverting(_6(6.6.0):c1) Uninverting(_7(6.6.0):c1) 
Uninverting(_8(6.6.0):c1) Uninverting(_9(6.6.0):c1) Uninverting(_a(6.6.0):c1)))}
   [junit4]   2> 1861881 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1861881 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 17
   [junit4]   2> 1861882 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_s_dv&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1861883 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_s_dv,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1861883 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861884 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861884 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_s_dv,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1861885 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 1861886 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[8]} 0 0
   [junit4]   2> 1861886 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1861886 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ed2a62d
   [junit4]   2> 1861904 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@633bcb73[collection1] main]
   [junit4]   2> 1861904 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1861904 INFO  (searcherExecutor-9334-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@633bcb73[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.6.0):c1)
 Uninverting(_5(6.6.0):c1) Uninverting(_6(6.6.0):c1) Uninverting(_7(6.6.0):c1) 
Uninverting(_8(6.6.0):c1) Uninverting(_9(6.6.0):c1) Uninverting(_a(6.6.0):c1) 
Uninverting(_b(6.6.0):c1)))}
   [junit4]   2> 1861904 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 18
   [junit4]   2> 1861905 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_s_dvo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1861906 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_s_dvo2,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1861906 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861907 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] 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> 1861907 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_s_dvo2,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1861908 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
   [junit4]   2> 1861910 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[7D1C775D54D632E8])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[9]} 0 0
   [junit4]   2> 1861910 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMul

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

letes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1867565 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[7D1C775D54D632E8]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3f6b1aa1
   [junit4]   2> 1867593 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[7D1C775D54D632E8]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@25039f7d[collection1] main]
   [junit4]   2> 1867594 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[7D1C775D54D632E8]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1867594 INFO  (searcherExecutor-9346-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@25039f7d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_55(6.6.0):c1)))}
   [junit4]   2> 1867601 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[7D1C775D54D632E8]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 36
   [junit4]   2> 1867602 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[7D1C775D54D632E8]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 1867603 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[7D1C775D54D632E8]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1867603 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[7D1C775D54D632E8]) [    ] 
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> 1867603 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[7D1C775D54D632E8]) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1867603 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[7D1C775D54D632E8]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=79124428
   [junit4]   2> 1867603 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[7D1C775D54D632E8]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1867605 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[7D1C775D54D632E8]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 1867606 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[7D1C775D54D632E8]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 1867607 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[7D1C775D54D632E8]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1867608 INFO  (coreCloseExecutor-9350-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@375c2105
   [junit4]   2> 1867617 INFO  (coreCloseExecutor-9350-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 1867627 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[7D1C775D54D632E8]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testOnEmptyIndex
   [junit4]   2> 1867628 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[7D1C775D54D632E8]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_7D1C775D54D632E8-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): {}, 
docValues:{}, maxPointsInLeafNode=1954, maxMBSortInHeap=6.124896040564158, 
sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=he-IL, 
timezone=America/Chicago
   [junit4]   2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 
1.8.0_121 (64-bit)/cpus=4,threads=1,free=150923568,total=534249472
   [junit4]   2> NOTE: All tests run in this JVM: [OverseerTest, 
RequestLoggingTest, TestLMJelinekMercerSimilarityFactory, 
TestTolerantUpdateProcessorRandomCloud, BinaryUpdateRequestHandlerTest, 
SparseHLLTest, TestAddFieldRealTimeGet, TestDefaultStatsCache, 
TestClassicSimilarityFactory, DocValuesMultiTest, ZkStateWriterTest, 
CreateCollectionCleanupTest, TestCloudPseudoReturnFields, UpdateParamsTest, 
ManagedSchemaRoundRobinCloudTest, CoreMergeIndexesAdminHandlerTest, 
ReplaceNodeTest, OverseerRolesTest, HttpSolrCallGetCoreTest, 
TestPhraseSuggestions, TestPostingsSolrHighlighter, TestShortCircuitedRequests, 
TestDynamicFieldResource, RulesTest, TestConfig, TestRestoreCore, 
TestSolrQueryResponse, SuggestComponentContextFilterQueryTest, TestMacros, 
TestRebalanceLeaders, StatsReloadRaceTest, TestSolrCoreParser, StressHdfsTest, 
SimpleFacetsTest, SuggesterTSTTest, CdcrVersionReplicationTest, 
TestMultiWordSynonyms, RecoveryZkTest, TestFileDictionaryLookup, 
TestBulkSchemaConcurrent, SolrPluginUtilsTest, TestPushWriter, 
TestDistribDocBasedVersion, TestCloudInspectUtil, TestAnalyzedSuggestions, 
TestMergePolicyConfig, BadIndexSchemaTest, TestLFUCache, 
TestSolrDeletionPolicy1, TestOnReconnectListenerSupport, StatsComponentTest, 
TestFieldTypeCollectionResource, AnalysisErrorHandlingTest, 
HdfsChaosMonkeyNothingIsSafeTest, BadComponentTest, 
FullSolrCloudDistribCmdsTest, BJQParserTest, 
BigEndianAscendingWordSerializerTest, BlockJoinFacetSimpleTest, 
PeerSyncWithIndexFingerprintCachingTest, TestHdfsBackupRestoreCore, 
SimpleCollectionCreateDeleteTest, DistributedExpandComponentTest, 
TestJmxMonitoredMap, TestFieldCache, FieldMutatingUpdateProcessorTest, 
TestSolrQueryParser, TestCollationField, SolrMetricManagerTest, 
TestManagedSchema, OverriddenZkACLAndCredentialsProvidersTest, 
TestExtendedDismaxParser, TestCorePropertiesReload, BadCopyFieldTest, 
TestRequestStatusCollectionAPI, DistributedSuggestComponentTest, 
BlockCacheTest, SolrTestCaseJ4Test, PropertiesRequestHandlerTest, 
LukeRequestHandlerTest, SolrGangliaReporterTest, MergeStrategyTest, 
PrimitiveFieldTypeTest, TestManagedResourceStorage, TestUniqueKeyFieldResource, 
UnloadDistributedZkTest, ActionThrottleTest, TestMinMaxOnMultiValuedField, 
TimeZoneUtilsTest, MinimalSchemaTest, TestSimpleQParserPlugin, 
PingRequestHandlerTest, StatelessScriptUpdateProcessorFactoryTest, 
JSONWriterTest, TestPKIAuthenticationPlugin, ShowFileRequestHandlerTest, 
TestInitQParser, SuggesterWFSTTest, TestSimpleTextCodec, 
ReplicaListTransformerTest, TestWriterPerf, 
ConcurrentDeleteAndCreateCollectionTest, UUIDUpdateProcessorFallbackTest, 
TestReversedWildcardFilterFactory, CloudExitableDirectoryReaderTest, 
LeaderElectionTest, DirectUpdateHandlerTest, TestDistributedMissingSort, 
TemplateUpdateProcessorTest, TestXmlQParserPlugin, TestHdfsCloudBackupRestore, 
HardAutoCommitTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, 
OpenCloseCoreStressTest, ShardRoutingTest, TestReplicationHandler, ZkCLITest, 
AutoCommitTest, TestRecovery, ZkControllerTest, TestReload, SolrCoreTest, 
QueryElevationComponentTest, TestFunctionQuery, BasicFunctionalityTest, 
TestLazyCores, HighlighterTest, DistributedQueryElevationComponentTest, 
CurrencyFieldXmlFileTest, SolrIndexSplitterTest, TestCoreDiscovery, 
SpellCheckCollatorTest, CoreAdminHandlerTest, SuggesterTest, TestStressLucene, 
TestTrie, TestCSVLoader, NoCacheHeaderTest, SchemaVersionSpecificBehaviorTest, 
SolrCoreCheckLockOnStartupTest, TestUpdate, QueryEqualityTest, 
DirectUpdateHandlerOptimizeTest, DistanceFunctionTest, SolrInfoMBeanTest, 
XsltUpdateRequestHandlerTest, DebugComponentTest, CacheHeaderTest, 
IndexBasedSpellCheckerTest, TestSurroundQueryParser, DisMaxRequestHandlerTest, 
StandardRequestHandlerTest, TestOmitPositions, XmlUpdateRequestHandlerTest, 
RequestHandlersTest, TermVectorComponentTest, FieldAnalysisRequestHandlerTest, 
LoggingHandlerTest, TestJmxIntegration, UpdateRequestProcessorFactoryTest, 
QueryParsingTest, TestPHPSerializedResponseWriter, TestBinaryResponseWriter, 
TestQuerySenderListener, TestDocSet, TestSearchPerf, TestSolrCoreProperties, 
TestLuceneMatchVersion, SynonymTokenizerTest, EchoParamsTest, 
TestSweetSpotSimilarityFactory, TestPerFieldSimilarity, 
TestLMDirichletSimilarityFactory, ResourceLoaderTest, PluginInfoTest, 
PreAnalyzedFieldTest, TestSystemIdResolver, PrimUtilsTest, 
SpellingQueryConverterTest, RAMDirectoryFactoryTest, TestSolrJ, 
ZkNodePropsTest, CircularListTest, CursorPagingTest, TestCrossCoreJoin, 
TestCursorMarkWithoutUniqueKey, TestHighlightDedupGrouping, TestTolerantSearch, 
TestEmbeddedSolrServerConstructors, TestEmbeddedSolrServerSchemaAPI, 
TestJettySolrRunner, AliasIntegrationTest, BaseCdcrDistributedZkTest, 
CdcrBootstrapTest, DeleteNodeTest, DistribDocExpirationUpdateProcessorTest, 
HttpPartitionTest, MultiThreadedOCPTest, SharedFSAutoReplicaFailoverUtilsTest, 
SolrCLIZkUtilsTest, SolrCloudExampleTest, TestCloudRecovery, TestCollectionAPI, 
TestLeaderElectionWithEmptyReplica, TestLeaderInitiatedRecoveryThread, 
TestLockTree, TestMiniSolrCloudCluster, TlogReplayBufferedWhileIndexingTest, 
TriLevelCompositeIdRoutingTest, VMParamsZkACLAndCredentialsProvidersTest, 
HdfsBasicDistributedZk2Test, HdfsBasicDistributedZkTest, 
HdfsCollectionsAPIDistributedZkTest, HdfsNNFailoverTest, HdfsRecoveryZkTest, 
CachingDirectoryFactoryTest, QueryResultKeyTest, TestBackupRepositoryFactory, 
TestConfigSetImmutable, TestConfigSetProperties, TestConfigSets, 
TestDynamicLoading, V2ApiIntegrationTest, CoreAdminCreateDiscoverTest, 
InfoHandlerTest, SecurityConfHandlerTest, SegmentsInfoRequestHandlerTest, 
TestApiFramework, TestConfigsApi, DistributedDebugComponentTest, 
DistributedFacetExistsSmallTest, DistributedFacetPivotSmallTest, 
DistributedFacetPivotWhiteBoxTest, FacetPivotSmallTest, InfixSuggestersTest, 
TestDistributedStatsComponentCardinality, TestSchemaManager, 
TestUseDocValuesAsStored]
   [junit4] Completed [635/711 (1!)] on J2 in 7.22s, 6 tests, 1 error <<< 
FAILURES!

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

Reply via email to