Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/3241/
Java: 32bit/jdk1.8.0_121 -client -XX:+UseParallelGC

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([FB71B0C6134597DE:C95BB758EBBBB307]:0)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:969)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:916)
        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 13135 lines...]
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/init-core-data-001
   [junit4]   2> 1895007 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[FB71B0C6134597DE]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 1895008 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[FB71B0C6134597DE]-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> 1895009 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.SolrTestCaseJ4 ###Starting testMultivaluedOrdering
   [junit4]   2> 1895011 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1895021 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1895028 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] 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> 1895033 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1895041 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 1895042 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 1895078 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1895080 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1895103 WARN  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using 
first MBeanServer.
   [junit4]   2> 1895108 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@187282d
   [junit4]   2> 1895108 WARN  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using 
first MBeanServer.
   [junit4]   2> 1895114 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@187282d
   [junit4]   2> 1895114 WARN  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using 
first MBeanServer.
   [junit4]   2> 1895114 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@187282d
   [junit4]   2> 1895128 INFO  (coreLoadExecutor-6617-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1895138 INFO  (coreLoadExecutor-6617-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 1895153 INFO  (coreLoadExecutor-6617-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 1895153 INFO  (coreLoadExecutor-6617-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-001/collection1,
 trusted=true
   [junit4]   2> 1895154 WARN  (coreLoadExecutor-6617-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 1895154 INFO  (coreLoadExecutor-6617-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@187282d
   [junit4]   2> 1895154 INFO  (coreLoadExecutor-6617-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1895154 INFO  (coreLoadExecutor-6617-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-001/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/init-core-data-001/]
   [junit4]   2> 1895157 INFO  (coreLoadExecutor-6617-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=47, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=69.494140625, 
floorSegmentMB=0.6875, forceMergeDeletesPctAllowed=8.931025092466577, 
segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1895175 WARN  (coreLoadExecutor-6617-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> 1895224 INFO  (coreLoadExecutor-6617-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1895224 INFO  (coreLoadExecutor-6617-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1895225 INFO  (coreLoadExecutor-6617-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=45, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7911055295154367]
   [junit4]   2> 1895226 INFO  (coreLoadExecutor-6617-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@11a004b[collection1] 
main]
   [junit4]   2> 1895226 INFO  (coreLoadExecutor-6617-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-001/collection1/conf
   [junit4]   2> 1895227 INFO  (coreLoadExecutor-6617-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1895227 INFO  
(searcherExecutor-6618-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@11a004b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1895228 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1895229 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1895229 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] 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> 1895230 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[1]} 0 1
   [junit4]   2> 1895234 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[2]} 0 0
   [junit4]   2> 1895235 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[3]} 0 0
   [junit4]   2> 1895236 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[4]} 0 0
   [junit4]   2> 1895237 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1895237 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@238447
   [junit4]   2> 1895362 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@eb9481[collection1] main]
   [junit4]   2> 1895363 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1895363 INFO  (searcherExecutor-6618-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@eb9481[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C4)))}
   [junit4]   2> 1895364 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 127
   [junit4]   2> 1895365 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] 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> 1895366 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] 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> 1895366 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] 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> 1895367 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] 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> 1895367 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1895367 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=5184720
   [junit4]   2> 1895367 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1895368 INFO  (coreCloseExecutor-6622-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1422a0d
   [junit4]   2> 1895369 INFO  (coreCloseExecutor-6622-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 1895372 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1895374 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[FB71B0C6134597DE]) 
[    ] o.a.s.SolrTestCaseJ4 ###Ending testMultivaluedOrdering
   [junit4]   2> 1895377 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testUseDocValuesAsStoredFalse
   [junit4]   2> 1895379 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1895389 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1895396 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] 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> 1895399 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1895408 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 1895409 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-002/collection1/conf/managed-schema
   [junit4]   2> 1895443 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1895445 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1895466 WARN  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 1895470 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@187282d
   [junit4]   2> 1895470 WARN  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 1895476 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@187282d
   [junit4]   2> 1895476 WARN  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 1895476 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@187282d
   [junit4]   2> 1895489 INFO  (coreLoadExecutor-6629-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1895498 INFO  (coreLoadExecutor-6629-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 1895507 INFO  (coreLoadExecutor-6629-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 1895507 INFO  (coreLoadExecutor-6629-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-002/collection1,
 trusted=true
   [junit4]   2> 1895508 WARN  (coreLoadExecutor-6629-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 1895508 INFO  (coreLoadExecutor-6629-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@187282d
   [junit4]   2> 1895508 INFO  (coreLoadExecutor-6629-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1895508 INFO  (coreLoadExecutor-6629-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-002/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/init-core-data-001/]
   [junit4]   2> 1895512 WARN  (coreLoadExecutor-6629-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> 1895557 INFO  (coreLoadExecutor-6629-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1895557 INFO  (coreLoadExecutor-6629-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1895559 INFO  (coreLoadExecutor-6629-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=47, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=69.494140625, 
floorSegmentMB=0.6875, forceMergeDeletesPctAllowed=8.931025092466577, 
segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1895562 INFO  (coreLoadExecutor-6629-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@180b347[collection1] 
main]
   [junit4]   2> 1895562 INFO  (coreLoadExecutor-6629-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-002/collection1/conf
   [junit4]   2> 1895563 INFO  (coreLoadExecutor-6629-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1895563 INFO  
(searcherExecutor-6630-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@180b347[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C4)))}
   [junit4]   2> 1895564 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1895565 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid]} 0 1
   [junit4]   2> 1895566 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1895566 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3ce828
   [junit4]   2> 1895695 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@162895d[collection1] main]
   [junit4]   2> 1895696 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1895696 INFO  (searcherExecutor-6630-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@162895d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C4)
 Uninverting(_1(6.6.0):C1)))}
   [junit4]   2> 1895696 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 130
   [junit4]   2> 1895697 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] 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> 1895698 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] 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> 1895699 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] 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> 1895700 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] 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> 1895700 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1895700 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=33129333
   [junit4]   2> 1895700 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1895700 INFO  (coreCloseExecutor-6634-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1a95a1a
   [junit4]   2> 1895702 INFO  (coreCloseExecutor-6634-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 1895705 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1895707 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[FB71B0C6134597DE])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testUseDocValuesAsStoredFalse
   [junit4]   2> 1895709 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testMultipleSearchResults
   [junit4]   2> 1895712 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1895726 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1895734 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] 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> 1895737 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1895745 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 1895747 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-003/collection1/conf/managed-schema
   [junit4]   2> 1895778 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1895780 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1895803 WARN  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 1895807 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@187282d
   [junit4]   2> 1895808 WARN  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 1895813 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@187282d
   [junit4]   2> 1895813 WARN  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 1895814 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@187282d
   [junit4]   2> 1895826 INFO  (coreLoadExecutor-6641-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1895835 INFO  (coreLoadExecutor-6641-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 1895844 INFO  (coreLoadExecutor-6641-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 1895844 INFO  (coreLoadExecutor-6641-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-003/collection1,
 trusted=true
   [junit4]   2> 1895844 WARN  (coreLoadExecutor-6641-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 1895845 INFO  (coreLoadExecutor-6641-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@187282d
   [junit4]   2> 1895845 INFO  (coreLoadExecutor-6641-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1895845 INFO  (coreLoadExecutor-6641-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-003/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/init-core-data-001/]
   [junit4]   2> 1895848 WARN  (coreLoadExecutor-6641-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> 1895892 INFO  (coreLoadExecutor-6641-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1895892 INFO  (coreLoadExecutor-6641-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1895894 INFO  (coreLoadExecutor-6641-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=47, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=69.494140625, 
floorSegmentMB=0.6875, forceMergeDeletesPctAllowed=8.931025092466577, 
segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1895897 INFO  (coreLoadExecutor-6641-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1bb4638[collection1] 
main]
   [junit4]   2> 1895898 INFO  (coreLoadExecutor-6641-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-003/collection1/conf
   [junit4]   2> 1895898 INFO  (coreLoadExecutor-6641-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1895898 INFO  
(searcherExecutor-6642-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1bb4638[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C4)
 Uninverting(_1(6.6.0):C1)))}
   [junit4]   2> 1895899 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1895901 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid1]} 0 1
   [junit4]   2> 1895902 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid2]} 0 0
   [junit4]   2> 1895902 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid3]} 0 0
   [junit4]   2> 1895904 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid4]} 0 0
   [junit4]   2> 1895904 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid5]} 0 0
   [junit4]   2> 1895905 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid6]} 0 0
   [junit4]   2> 1895905 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1895905 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@9c9e71
   [junit4]   2> 1896072 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@f056e8[collection1] main]
   [junit4]   2> 1896073 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1896073 INFO  (searcherExecutor-6642-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@f056e8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C4)
 Uninverting(_1(6.6.0):C1) Uninverting(_2(6.6.0):C6)))}
   [junit4]   2> 1896073 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 167
   [junit4]   2> 1896075 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid*&fl=*&wt=xml} hits=7 status=0 QTime=1
   [junit4]   2> 1896076 ERROR 
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] 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":1},
   [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> 1896077 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1896077 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=30392710
   [junit4]   2> 1896077 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1896080 INFO  (coreCloseExecutor-6646-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1c24ee8
   [junit4]   2> 1896081 INFO  (coreCloseExecutor-6646-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 1896086 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1896087 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[FB71B0C6134597DE])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testMultipleSearchResults
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestUseDocValuesAsStored -Dtests.method=testMultipleSearchResults 
-Dtests.seed=FB71B0C6134597DE -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=und -Dtests.timezone=Etc/GMT-13 -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.38s J2 | 
TestUseDocValuesAsStored.testMultipleSearchResults <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: mismatch: 
'myid1'!='myid' @ response/docs/[0]/id
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([FB71B0C6134597DE:C95BB758EBBBB307]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:969)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:916)
   [junit4]    >        at 
org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults(TestUseDocValuesAsStored.java:243)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 1896091 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testOnEmptyIndex
   [junit4]   2> 1896093 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1896103 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1896110 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
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> 1896113 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1896127 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 1896128 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-004/collection1/conf/managed-schema
   [junit4]   2> 1896152 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed 
the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-004/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-004/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1896154 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1896176 WARN  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first 
MBeanServer.
   [junit4]   2> 1896181 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@187282d
   [junit4]   2> 1896181 WARN  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first 
MBeanServer.
   [junit4]   2> 1896186 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@187282d
   [junit4]   2> 1896186 WARN  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first 
MBeanServer.
   [junit4]   2> 1896186 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@187282d
   [junit4]   2> 1896198 INFO  (coreLoadExecutor-6653-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1896208 INFO  (coreLoadExecutor-6653-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 1896217 INFO  (coreLoadExecutor-6653-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 1896217 INFO  (coreLoadExecutor-6653-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-004/collection1,
 trusted=true
   [junit4]   2> 1896217 WARN  (coreLoadExecutor-6653-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 1896217 INFO  (coreLoadExecutor-6653-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@187282d
   [junit4]   2> 1896217 INFO  (coreLoadExecutor-6653-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1896217 INFO  (coreLoadExecutor-6653-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-004/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/init-core-data-001/]
   [junit4]   2> 1896221 WARN  (coreLoadExecutor-6653-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> 1896267 INFO  (coreLoadExecutor-6653-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1896267 INFO  (coreLoadExecutor-6653-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1896269 INFO  (coreLoadExecutor-6653-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=47, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=69.494140625, 
floorSegmentMB=0.6875, forceMergeDeletesPctAllowed=8.931025092466577, 
segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1896273 INFO  (coreLoadExecutor-6653-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1312936[collection1] 
main]
   [junit4]   2> 1896274 INFO  (coreLoadExecutor-6653-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-004/collection1/conf
   [junit4]   2> 1896274 INFO  (coreLoadExecutor-6653-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1896274 INFO  
(searcherExecutor-6654-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1312936[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C4)
 Uninverting(_1(6.6.0):C1) Uninverting(_2(6.6.0):C6)))}
   [junit4]   2> 1896276 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1896277 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1896277 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
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> 1896278 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1896278 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@140239d
   [junit4]   2> 1896289 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4fc074[collection1] main]
   [junit4]   2> 1896290 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1896290 INFO  (searcherExecutor-6654-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4fc074[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1896291 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 13
   [junit4]   2> 1896292 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 1896292 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1896292 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1896293 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*,test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1896294 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[xyz]} 0 0
   [junit4]   2> 1896294 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 1896295 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1896295 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1896295 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*,test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1896295 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1896296 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@140239d
   [junit4]   2> 1896407 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@fcbc4f[collection1] main]
   [junit4]   2> 1896408 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1896408 INFO  (searcherExecutor-6654-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@fcbc4f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.6.0):C1)))}
   [junit4]   2> 1896408 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 112
   [junit4]   2> 1896409 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 1896409 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1896409 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=test_nonstored_dv_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1896410 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*,test_nonstored_dv_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1896411 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[xyz]} 0 0
   [junit4]   2> 1896411 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1896411 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@140239d
   [junit4]   2> 1896481 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@2d10f5[collection1] main]
   [junit4]   2> 1896482 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1896482 INFO  (searcherExecutor-6654-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2d10f5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.6.0):C1)))}
   [junit4]   2> 1896482 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 71
   [junit4]   2> 1896483 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 1896484 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1896484 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=31510805
   [junit4]   2> 1896484 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1896484 INFO  (coreCloseExecutor-6658-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@211130
   [junit4]   2> 1896485 INFO  (coreCloseExecutor-6658-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 1896489 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1896490 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[FB71B0C6134597DE]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testOnEmptyIndex
   [junit4]   2> 1896493 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued
   [junit4]   2> 1896496 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1896506 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1896513 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] 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> 1896516 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1896525 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 1896526 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-005/collection1/conf/managed-schema
   [junit4]   2> 1896561 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-005/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-005/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1896563 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1896585 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 1896589 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@187282d
   [junit4]   2> 1896589 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 1896595 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@187282d
   [junit4]   2> 1896595 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, 
using first MBeanServer.
   [junit4]   2> 1896595 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@187282d
   [junit4]   2> 1896608 INFO  (coreLoadExecutor-6665-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1896623 INFO  (coreLoadExecutor-6665-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 1896632 INFO  (coreLoadExecutor-6665-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 1896632 INFO  (coreLoadExecutor-6665-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-005/collection1,
 trusted=true
   [junit4]   2> 1896633 WARN  (coreLoadExecutor-6665-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 1896633 INFO  (coreLoadExecutor-6665-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@187282d
   [junit4]   2> 1896633 INFO  (coreLoadExecutor-6665-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1896633 INFO  (coreLoadExecutor-6665-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-005/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/init-core-data-001/]
   [junit4]   2> 1896637 WARN  (coreLoadExecutor-6665-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> 1896679 INFO  (coreLoadExecutor-6665-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1896679 INFO  (coreLoadExecutor-6665-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1896681 INFO  (coreLoadExecutor-6665-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=47, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=69.494140625, 
floorSegmentMB=0.6875, forceMergeDeletesPctAllowed=8.931025092466577, 
segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1896683 INFO  (coreLoadExecutor-6665-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b99cb6[collection1] 
main]
   [junit4]   2> 1896683 INFO  (coreLoadExecutor-6665-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-005/collection1/conf
   [junit4]   2> 1896683 INFO  (coreLoadExecutor-6665-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1896684 INFO  
(searcherExecutor-6666-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1b99cb6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.6.0):C1)))}
   [junit4]   2> 1896685 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1896686 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1896686 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] 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> 1896688 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 1
   [junit4]   2> 1896688 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1896689 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3908b3
   [junit4]   2> 1896792 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c315b5[collection1] main]
   [junit4]   2> 1896793 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1896793 INFO  (searcherExecutor-6666-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1c315b5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(6.6.0):C1)))}
   [junit4]   2> 1896794 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 105
   [junit4]   2> 1896795 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_s_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1896797 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_s_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1896798 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] 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> 1896799 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] 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> 1896800 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_s_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1896801 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1896805 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 2
   [junit4]   2> 1896805 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1896806 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3908b3
   [junit4]   2> 1896916 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@619478[collection1] main]
   [junit4]   2> 1896917 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1896917 INFO  (searcherExecutor-6666-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@619478[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(6.6.0):C1)
 Uninverting(_6(6.6.0):C1)))}
   [junit4]   2> 1896917 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 111
   [junit4]   2> 1896919 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_i_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1896920 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_i_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1896920 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] 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> 1896922 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] 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> 1896923 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_i_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1896924 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 1896928 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 2
   [junit4]   2> 1896929 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1896929 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3908b3
   [junit4]   2> 1897074 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@a2c635[collection1] main]
   [junit4]   2> 1897074 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1897074 INFO  (searcherExecutor-6666-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@a2c635[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(6.6.0):C1)
 Uninverting(_6(6.6.0):C1) Uninverting(_7(6.6.0):C1)))}
   [junit4]   2> 1897075 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 146
   [junit4]   2> 1897076 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] 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=1
   [junit4]   2> 1897082 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] 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> 1897086 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] 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> 1897089 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] 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> 1897094 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] 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> 1897098 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 1897103 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[4]} 0 1
   [junit4]   2> 1897104 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1897104 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3908b3
   [junit4]   2> 1897228 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1170efb[collection1] main]
   [junit4]   2> 1897229 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1897229 INFO  (searcherExecutor-6666-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1170efb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(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> 1897230 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 126
   [junit4]   2> 1897232 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_l_dvo&wt=xml} hits=1 status=0 QTime=1
   [junit4]   2> 1897236 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_l_dvo,*&wt=xml} hits=1 status=0 QTime=2
   [junit4]   2> 1897238 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1897239 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] 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> 1897241 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_l_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=1
   [junit4]   2> 1897242 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 1897244 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 0
   [junit4]   2> 1897244 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1897244 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3908b3
   [junit4]   2> 1897366 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@27cf24[collection1] main]
   [junit4]   2> 1897368 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1897368 INFO  (searcherExecutor-6666-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@27cf24[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(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> 1897368 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 124
   [junit4]   2> 1897370 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_fs_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1897371 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_fs_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1897373 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] 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> 1897374 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] 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> 1897375 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_fs_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1897376 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1897382 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[6]} 0 2
   [junit4]   2> 1897382 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1897383 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3908b3
   [junit4]   2> 1897527 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6f5efd[collection1] main]
   [junit4]   2> 1897528 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1897528 INFO  (searcherExecutor-6666-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6f5efd[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(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> 1897529 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 146
   [junit4]   2> 1897530 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=test_dts_dvo&wt=xml} hits=1 status=0 QTime=1
   [junit4]   2> 1897534 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=test_dts_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1897538 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] 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> 1897542 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] 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> 1897545 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:6&fl=test_dts_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1897552 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 1897561 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[7]} 0 2
   [junit4]   2> 1897562 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1897562 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3908b3
   [junit4]   2> 1897678 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ab06ea[collection1] main]
   [junit4]   2> 1897679 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1897680 INFO  (searcherExecutor-6666-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1ab06ea[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(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> 1897680 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 118
   [junit4]   2> 1897681 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_ss_dv&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1897683 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_ss_dv,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1897684 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] 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> 1897685 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] 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> 1897687 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[FB71B0C6134597DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_ss_dv,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1

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

conf
   [junit4]   2> 1938819 INFO  (coreLoadExecutor-6677-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1938819 INFO  
(searcherExecutor-6678-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@ef4816[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7e(6.6.0):C1)
 Uninverting(_7f(6.6.0):C1) Uninverting(_7g(6.6.0):C1) 
Uninverting(_7h(6.6.0):C1) Uninverting(_7i(6.6.0):C1) 
Uninverting(_7j(6.6.0):C1) Uninverting(_7k(6.6.0):C1) 
Uninverting(_7l(6.6.0):C1) Uninverting(_7m(6.6.0):C1)))}
   [junit4]   2> 1938821 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[FB71B0C6134597DE]) [    
] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1938822 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[FB71B0C6134597DE]) [    
] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-006/collection1/conf/managed-schema
   [junit4]   2> 1938836 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[FB71B0C6134597DE]) [    
] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001/tempDir-006/collection1/conf/managed-schema
   [junit4]   2> 1938841 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[FB71B0C6134597DE]) [    
] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1938842 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[FB71B0C6134597DE]) [    
] 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> 1938843 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[FB71B0C6134597DE]) [    
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[myid1]} 0 0
   [junit4]   2> 1938843 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[FB71B0C6134597DE]) [    
] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1938843 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[FB71B0C6134597DE]) [    
] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@119f861
   [junit4]   2> 1939627 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[FB71B0C6134597DE]) [    
] o.a.s.s.SolrIndexSearcher Opening [Searcher@d62b51[collection1] main]
   [junit4]   2> 1939628 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[FB71B0C6134597DE]) [    
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1939628 INFO  (searcherExecutor-6678-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@d62b51[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7n(6.6.0):C1)))}
   [junit4]   2> 1939632 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[FB71B0C6134597DE]) [    
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 789
   [junit4]   2> 1939633 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[FB71B0C6134597DE]) [    
] 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> 1939634 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[FB71B0C6134597DE]) [    
] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1939634 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[FB71B0C6134597DE]) [    
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=14661550
   [junit4]   2> 1939634 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[FB71B0C6134597DE]) [    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1939634 INFO  (coreCloseExecutor-6682-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1b3fbbd
   [junit4]   2> 1939635 INFO  (coreCloseExecutor-6682-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 1939639 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[FB71B0C6134597DE]) [    
] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1939642 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[FB71B0C6134597DE]) [    
] o.a.s.SolrTestCaseJ4 ###Ending testManagedSchema
   [junit4]   2> 1939644 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[FB71B0C6134597DE]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_FB71B0C6134597DE-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): {}, 
docValues:{}, maxPointsInLeafNode=699, maxMBSortInHeap=5.0422724433145465, 
sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=und, 
timezone=Etc/GMT-13
   [junit4]   2> NOTE: Linux 4.4.0-72-generic i386/Oracle Corporation 1.8.0_121 
(32-bit)/cpus=12,threads=1,free=143586816,total=395575296
   [junit4]   2> NOTE: All tests run in this JVM: [PreAnalyzedFieldTest, 
SuggesterTSTTest, SolrMetricsIntegrationTest, TestZkChroot, 
TermVectorComponentDistributedTest, TlogReplayBufferedWhileIndexingTest, 
SolrXmlInZkTest, HdfsChaosMonkeySafeLeaderTest, TestRandomFlRTGCloud, 
TestBulkSchemaAPI, FullSolrCloudDistribCmdsTest, TestSizeLimitedDistributedMap, 
BinaryUpdateRequestHandlerTest, HdfsSyncSliceTest, CircularListTest, 
TestExportWriter, TestDownShardTolerantSearch, CheckHdfsIndexTest, 
HardAutoCommitTest, TestLMJelinekMercerSimilarityFactory, PluginInfoTest, 
DistributedQueryComponentOptimizationTest, TestCoreDiscovery, 
TestPhraseSuggestions, TestDefaultStatsCache, TestMacroExpander, 
TestUninvertingReader, ConfigureRecoveryStrategyTest, RequestHandlersTest, 
SolrCoreMetricManagerTest, TestCollationFieldDocValues, BadIndexSchemaTest, 
TestSolrCLIRunExample, DOMUtilTest, TestBinaryField, TestSmileRequest, 
RecoveryZkTest, TestDFRSimilarityFactory, AutoCommitTest, 
AsyncCallRequestStatusResponseTest, TestConfigSetsAPI, 
TestPostingsSolrHighlighter, TestTrieFacet, DebugComponentTest, 
AnalyticsMergeStrategyTest, ShardSplitTest, CoreAdminHandlerTest, 
TestCloudInspectUtil, BadComponentTest, TestSchemaSimilarityResource, 
QueryParsingTest, TestInfoStreamLogging, NoCacheHeaderTest, TestJsonRequest, 
HdfsLockFactoryTest, BasicDistributedZk2Test, TestTrackingShardHandlerFactory, 
SimpleFacetsTest, CollectionTooManyReplicasTest, 
SolrCoreCheckLockOnStartupTest, HdfsRecoverLeaseTest, TestXIncludeConfig, 
TestFreeTextSuggestions, TolerantUpdateProcessorTest, OverseerRolesTest, 
SuggesterWFSTTest, TestTolerantSearch, CdcrVersionReplicationTest, 
TestSubQueryTransformer, TestUtils, SpellPossibilityIteratorTest, 
TestLeaderElectionWithEmptyReplica, TestFieldTypeResource, 
TestSolrCloudWithSecureImpersonation, SubstringBytesRefFilterTest, 
TestComplexPhraseLeadingWildcard, TestCollationField, TestLegacyFieldCache, 
TestSolrFieldCacheMBean, JsonValidatorTest, TestSolrCoreSnapshots, 
OverseerTest, TestIndexingPerformance, DistribDocExpirationUpdateProcessorTest, 
TestReload, TestDelegationWithHadoopAuth, DocValuesMissingTest, TestOrdValues, 
TestFieldCacheSortRandom, CurrencyFieldXmlFileTest, 
TestDistributedStatsComponentCardinality, TestSolrQueryParser, 
TestSystemCollAutoCreate, LeaderElectionTest, 
BigEndianAscendingWordDeserializerTest, RollingRestartTest, TestReloadDeadlock, 
MigrateRouteKeyTest, UpdateLogTest, TestSchemalessBufferedUpdates, 
BlockJoinFacetDistribTest, PrimitiveFieldTypeTest, TestSSLRandomization, 
TestMergePolicyConfig, TestConfigReload, TestRecovery, 
HdfsUnloadDistributedZkTest, TestConfigSetProperties, 
CdcrReplicationDistributedZkTest, BooleanFieldTest, 
DistributedDebugComponentTest, SolrJmxReporterTest, SolrTestCaseJ4Test, 
TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort, 
TestEmbeddedSolrServerSchemaAPI, TestJettySolrRunner, ActionThrottleTest, 
CdcrReplicationHandlerTest, CleanupOldIndexTest, CollectionStateFormat2Test, 
CollectionsAPISolrJTest, CreateCollectionCleanupTest, 
DeleteLastCustomShardedReplicaTest, DeleteShardTest, DistribCursorPagingTest, 
HttpPartitionTest, MissingSegmentRecoveryTest, MultiThreadedOCPTest, 
TestClusterProperties, TestCryptoKeys, TestReplicaProperties, 
TestStressInPlaceUpdates, CoreSorterTest, DirectoryFactoryTest, 
ExitableDirectoryReaderTest, HdfsDirectoryFactoryTest, QueryResultKeyTest, 
TestBackupRepositoryFactory, TestConfigOverlay, TestConfigSetImmutable, 
TestImplicitCoreProperties, TestInitParams, TestReloadAndDeleteDocs, 
RequestLoggingTest, TestSQLHandler, DistributedFacetPivotSmallTest, 
InfixSuggestersTest, JavabinLoaderTest, HighlighterMaxOffsetTest, 
TestUnifiedSolrHighlighter, TestSlowCompositeReaderWrapper, 
SolrMetricManagerTest, SolrMetricReporterTest, SolrGangliaReporterTest, 
TestIntervalFaceting, TestJavabinTupleStreamParser, TestRawTransformer, 
TestSubQueryTransformerCrossCore, TestManagedResource, 
TestDefaultSearchFieldResource, TestDynamicFieldResource, 
TestFieldTypeCollectionResource, TestSchemaVersionResource, 
TestSolrQueryParserDefaultOperatorResource, TestUniqueKeyFieldResource, 
BadCopyFieldTest, ChangedSchemaMergeTest, DateRangeFieldTest, 
PreAnalyzedFieldManagedSchemaCloudTest, TestManagedSchemaAPI, 
TestManagedSchemaThreadSafety, TestUseDocValuesAsStored]
   [junit4] Completed [658/703 (1!)] on J2 in 44.66s, 6 tests, 1 error <<< 
FAILURES!

[...truncated 39543 lines...]

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

Reply via email to