Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-MacOSX/827/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseG1GC

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([E7F98B92BAB3A8DE:D5D38C0C424D8C07]:0)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:983)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:930)
        at 
org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults(TestUseDocValuesAsStored.java:243)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 13027 lines...]
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4]   2> Creating dataDir: 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/init-core-data-001
   [junit4]   2> 4782017 WARN  
(SUITE-TestUseDocValuesAsStored-seed#[E7F98B92BAB3A8DE]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1929 numCloses=1929
   [junit4]   2> 4782019 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[E7F98B92BAB3A8DE]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 4782027 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[E7F98B92BAB3A8DE]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) 
w/ MAC_OS_X supressed clientAuth
   [junit4]   2> 4782029 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testUseDocValuesAsStoredFalse
   [junit4]   2> 4782045 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 4782076 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 4782099 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4782104 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 4782118 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 4782120 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 4782125 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml
 to 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 4782134 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 4782741 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69d780bf
   [junit4]   2> 4782750 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69d780bf
   [junit4]   2> 4782751 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69d780bf
   [junit4]   2> 4782789 INFO  (coreLoadExecutor-9838-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 4782797 INFO  (coreLoadExecutor-9838-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 4782812 INFO  (coreLoadExecutor-9838-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 4782812 INFO  (coreLoadExecutor-9838-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-001/collection1,
 trusted=true
   [junit4]   2> 4782813 INFO  (coreLoadExecutor-9838-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@69d780bf
   [junit4]   2> 4782814 INFO  (coreLoadExecutor-9838-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 4782814 INFO  (coreLoadExecutor-9838-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-001/collection1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/init-core-data-001/]
   [junit4]   2> 4782819 INFO  (coreLoadExecutor-9838-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.11235207973829767]
   [junit4]   2> 4782838 WARN  (coreLoadExecutor-9838-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> 4782999 INFO  (coreLoadExecutor-9838-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4782999 INFO  (coreLoadExecutor-9838-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4783001 INFO  (coreLoadExecutor-9838-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=27, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=4.705078125, 
floorSegmentMB=1.4716796875, forceMergeDeletesPctAllowed=3.5399957055908313, 
segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5890758213813347
   [junit4]   2> 4783014 INFO  (coreLoadExecutor-9838-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@78d89c6b[collection1] main]
   [junit4]   2> 4783015 INFO  (coreLoadExecutor-9838-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-001/collection1/conf
   [junit4]   2> 4783016 INFO  (coreLoadExecutor-9838-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 4783016 INFO  
(searcherExecutor-9839-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@78d89c6b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4783018 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 4783020 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid]} 0 1
   [junit4]   2> 4783029 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4783029 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6a1ed0b2
   [junit4]   2> 4783060 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@28dc6871[collection1] main]
   [junit4]   2> 4783061 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4783061 INFO  (searcherExecutor-9839-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@28dc6871[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C1)))}
   [junit4]   2> 4783061 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 32
   [junit4]   2> 4783062 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4783062 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4783063 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4783063 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4783064 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 4783064 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4783064 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 4783064 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=174330225
   [junit4]   2> 4783065 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 4783065 INFO  (coreCloseExecutor-9843-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@5a74a846
   [junit4]   2> 4783081 INFO  (coreCloseExecutor-9843-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 4783087 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 4783088 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testUseDocValuesAsStoredFalse
   [junit4]   2> 4783090 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testMultipleSearchResults
   [junit4]   2> 4783097 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 4783165 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 4783178 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4783182 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 4783204 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 4783205 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-002/collection1/conf/managed-schema
   [junit4]   2> 4783220 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml
 to 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 4783224 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 4783320 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69d780bf
   [junit4]   2> 4783328 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69d780bf
   [junit4]   2> 4783329 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69d780bf
   [junit4]   2> 4783354 INFO  (coreLoadExecutor-9850-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 4783368 INFO  (coreLoadExecutor-9850-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 4783383 INFO  (coreLoadExecutor-9850-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 4783384 INFO  (coreLoadExecutor-9850-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-002/collection1,
 trusted=true
   [junit4]   2> 4783384 INFO  (coreLoadExecutor-9850-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@69d780bf
   [junit4]   2> 4783384 INFO  (coreLoadExecutor-9850-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 4783384 INFO  (coreLoadExecutor-9850-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-002/collection1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/init-core-data-001/]
   [junit4]   2> 4783392 WARN  (coreLoadExecutor-9850-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> 4783522 INFO  (coreLoadExecutor-9850-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4783522 INFO  (coreLoadExecutor-9850-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4783524 INFO  (coreLoadExecutor-9850-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.11235207973829767]
   [junit4]   2> 4783609 INFO  (coreLoadExecutor-9850-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@409984ac[collection1] main]
   [junit4]   2> 4783610 INFO  (coreLoadExecutor-9850-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-002/collection1/conf
   [junit4]   2> 4783610 INFO  (coreLoadExecutor-9850-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 4783610 INFO  
(searcherExecutor-9851-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@409984ac[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C1)))}
   [junit4]   2> 4783611 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 4783613 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid1]} 0 1
   [junit4]   2> 4783613 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid2]} 0 0
   [junit4]   2> 4783629 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid3]} 0 0
   [junit4]   2> 4783629 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid4]} 0 0
   [junit4]   2> 4783630 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid5]} 0 0
   [junit4]   2> 4783630 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid6]} 0 0
   [junit4]   2> 4783630 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4783630 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6ee47259
   [junit4]   2> 4783716 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2a95f1c5[collection1] main]
   [junit4]   2> 4783716 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4783716 INFO  (searcherExecutor-9851-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2a95f1c5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C1)
 Uninverting(_1(6.6.0):C6)))}
   [junit4]   2> 4783717 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 86
   [junit4]   2> 4783719 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4783720 ERROR 
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4783721 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 4783721 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4783721 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 4783721 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1276675770
   [junit4]   2> 4783722 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 4783722 INFO  (coreCloseExecutor-9855-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@6ad0dafd
   [junit4]   2> 4783745 INFO  (coreCloseExecutor-9855-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 4783749 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 4783751 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testMultipleSearchResults
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestUseDocValuesAsStored -Dtests.method=testMultipleSearchResults 
-Dtests.seed=E7F98B92BAB3A8DE -Dtests.slow=true -Dtests.locale=uk-UA 
-Dtests.timezone=Pacific/Nauru -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.67s J0 | 
TestUseDocValuesAsStored.testMultipleSearchResults <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: mismatch: 
'myid1'!='myid' @ response/docs/[0]/id
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([E7F98B92BAB3A8DE:D5D38C0C424D8C07]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:983)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:930)
   [junit4]    >        at 
org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults(TestUseDocValuesAsStored.java:243)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 4783760 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.SolrTestCaseJ4 ###Starting testManagedSchema
   [junit4]   2> 4783769 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 4783801 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 4783814 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] 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> 4783818 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 4783846 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 4783854 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-003/collection1/conf/managed-schema
   [junit4]   2> 4783873 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed 
the non-managed schema 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml
 to 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 4783877 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 4783945 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69d780bf
   [junit4]   2> 4783949 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69d780bf
   [junit4]   2> 4783949 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69d780bf
   [junit4]   2> 4783976 INFO  (coreLoadExecutor-9862-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 4783984 INFO  (coreLoadExecutor-9862-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 4783995 INFO  (coreLoadExecutor-9862-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 4783995 INFO  (coreLoadExecutor-9862-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-003/collection1,
 trusted=true
   [junit4]   2> 4783995 INFO  (coreLoadExecutor-9862-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@69d780bf
   [junit4]   2> 4783995 INFO  (coreLoadExecutor-9862-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 4783995 INFO  (coreLoadExecutor-9862-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-003/collection1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/init-core-data-001/]
   [junit4]   2> 4784000 WARN  (coreLoadExecutor-9862-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> 4784086 INFO  (coreLoadExecutor-9862-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4784086 INFO  (coreLoadExecutor-9862-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4784089 INFO  (coreLoadExecutor-9862-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.11235207973829767]
   [junit4]   2> 4784197 INFO  (coreLoadExecutor-9862-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1f10cf91[collection1] main]
   [junit4]   2> 4784204 INFO  (coreLoadExecutor-9862-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-003/collection1/conf
   [junit4]   2> 4784210 INFO  (coreLoadExecutor-9862-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 4784217 INFO  
(searcherExecutor-9863-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1f10cf91[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C1)
 Uninverting(_1(6.6.0):C6)))}
   [junit4]   2> 4784237 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 4784238 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-003/collection1/conf/managed-schema
   [junit4]   2> 4784242 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-003/collection1/conf/managed-schema
   [junit4]   2> 4784257 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 4784259 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] 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 2
   [junit4]   2> 4784260 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[myid1]} 0 1
   [junit4]   2> 4784260 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4784261 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@304c7cad
   [junit4]   2> 4784296 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.s.SolrIndexSearcher Opening [Searcher@6154e5ff[collection1] main]
   [junit4]   2> 4784296 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4784296 INFO  (searcherExecutor-9863-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6154e5ff[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):C1)))}
   [junit4]   2> 4784300 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 40
   [junit4]   2> 4784301 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] 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> 4784302 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 4784302 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] 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> 4784302 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 4784302 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=707714882
   [junit4]   2> 4784303 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 4784303 INFO  (coreCloseExecutor-9867-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@6baf17cf
   [junit4]   2> 4784362 INFO  (coreCloseExecutor-9867-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 4784366 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 4784368 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[E7F98B92BAB3A8DE]) [    
] o.a.s.SolrTestCaseJ4 ###Ending testManagedSchema
   [junit4]   2> 4784370 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testOnEmptyIndex
   [junit4]   2> 4784377 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 4784403 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 4784414 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
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> 4784417 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 4784428 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 4784428 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-004/collection1/conf/managed-schema
   [junit4]   2> 4784441 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed 
the non-managed schema 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-004/collection1/conf/schema-non-stored-docvalues.xml
 to 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-004/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 4784445 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 4784518 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69d780bf
   [junit4]   2> 4784523 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69d780bf
   [junit4]   2> 4784523 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69d780bf
   [junit4]   2> 4784545 INFO  (coreLoadExecutor-9874-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 4784553 INFO  (coreLoadExecutor-9874-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 4784586 INFO  (coreLoadExecutor-9874-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 4784586 INFO  (coreLoadExecutor-9874-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-004/collection1,
 trusted=true
   [junit4]   2> 4784586 INFO  (coreLoadExecutor-9874-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@69d780bf
   [junit4]   2> 4784586 INFO  (coreLoadExecutor-9874-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 4784586 INFO  (coreLoadExecutor-9874-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-004/collection1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/init-core-data-001/]
   [junit4]   2> 4784591 WARN  (coreLoadExecutor-9874-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> 4784670 INFO  (coreLoadExecutor-9874-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4784670 INFO  (coreLoadExecutor-9874-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4784672 INFO  (coreLoadExecutor-9874-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.11235207973829767]
   [junit4]   2> 4784680 INFO  (coreLoadExecutor-9874-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@5986b5ce[collection1] main]
   [junit4]   2> 4784681 INFO  (coreLoadExecutor-9874-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-004/collection1/conf
   [junit4]   2> 4784682 INFO  (coreLoadExecutor-9874-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 4784682 INFO  
(searcherExecutor-9875-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5986b5ce[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):C1)))}
   [junit4]   2> 4784684 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 4784684 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 4784685 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
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 1
   [junit4]   2> 4784685 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4784685 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@77aa5e5d
   [junit4]   2> 4784696 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@199abb32[collection1] main]
   [junit4]   2> 4784697 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4784697 INFO  (searcherExecutor-9875-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@199abb32[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4784700 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 14
   [junit4]   2> 4784700 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 4784700 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 4784701 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
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> 4784701 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
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> 4784702 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[xyz]} 0 0
   [junit4]   2> 4784702 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 4784702 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 4784702 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
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> 4784703 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
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> 4784703 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4784703 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@77aa5e5d
   [junit4]   2> 4784762 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5af85c48[collection1] main]
   [junit4]   2> 4784762 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4784763 INFO  (searcherExecutor-9875-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5af85c48[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.6.0):C1)))}
   [junit4]   2> 4784763 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 60
   [junit4]   2> 4784763 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 4784764 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4784764 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
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> 4784764 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
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> 4784765 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[xyz]} 0 0
   [junit4]   2> 4784765 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4784765 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@77aa5e5d
   [junit4]   2> 4784820 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5927185a[collection1] main]
   [junit4]   2> 4784821 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4784821 INFO  (searcherExecutor-9875-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5927185a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.6.0):C1)))}
   [junit4]   2> 4784826 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 61
   [junit4]   2> 4784827 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 4784828 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 4784828 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
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> 4784828 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 4784828 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=440827166
   [junit4]   2> 4784829 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 4784829 INFO  (coreCloseExecutor-9879-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1ac5e22f
   [junit4]   2> 4784831 INFO  (coreCloseExecutor-9879-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 4784835 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 4784836 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E7F98B92BAB3A8DE]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testOnEmptyIndex
   [junit4]   2> 4784838 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued
   [junit4]   2> 4784848 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 4784886 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 4784896 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4784898 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 4784909 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 4784910 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-005/collection1/conf/managed-schema
   [junit4]   2> 4784920 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-005/collection1/conf/schema-non-stored-docvalues.xml
 to 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-005/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 4784926 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 4784994 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69d780bf
   [junit4]   2> 4784998 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69d780bf
   [junit4]   2> 4784998 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69d780bf
   [junit4]   2> 4785025 INFO  (coreLoadExecutor-9886-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 4785036 INFO  (coreLoadExecutor-9886-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 4785047 INFO  (coreLoadExecutor-9886-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 4785047 INFO  (coreLoadExecutor-9886-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-005/collection1,
 trusted=true
   [junit4]   2> 4785047 INFO  (coreLoadExecutor-9886-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@69d780bf
   [junit4]   2> 4785048 INFO  (coreLoadExecutor-9886-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 4785048 INFO  (coreLoadExecutor-9886-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-005/collection1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/init-core-data-001/]
   [junit4]   2> 4785052 WARN  (coreLoadExecutor-9886-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> 4785161 INFO  (coreLoadExecutor-9886-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4785161 INFO  (coreLoadExecutor-9886-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4785163 INFO  (coreLoadExecutor-9886-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.11235207973829767]
   [junit4]   2> 4785168 INFO  (coreLoadExecutor-9886-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@461f6bd8[collection1] main]
   [junit4]   2> 4785169 INFO  (coreLoadExecutor-9886-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001/tempDir-005/collection1/conf
   [junit4]   2> 4785170 INFO  (coreLoadExecutor-9886-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 4785170 INFO  
(searcherExecutor-9887-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@461f6bd8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.6.0):C1)))}
   [junit4]   2> 4785172 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 4785172 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 4785173 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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 1
   [junit4]   2> 4785176 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 1
   [junit4]   2> 4785176 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4785176 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@44d43044
   [junit4]   2> 4785210 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@66f36cfc[collection1] main]
   [junit4]   2> 4785211 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4785211 INFO  (searcherExecutor-9887-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@66f36cfc[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(6.6.0):C1)))}
   [junit4]   2> 4785213 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 37
   [junit4]   2> 4785214 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_ss_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4785220 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_ss_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4785234 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4785258 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4785261 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_ss_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4785262 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4785266 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 1
   [junit4]   2> 4785269 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4785269 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@44d43044
   [junit4]   2> 4785343 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5b5aee3d[collection1] main]
   [junit4]   2> 4785344 INFO  (searcherExecutor-9887-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5b5aee3d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(6.6.0):C1)
 Uninverting(_6(6.6.0):C1)))}
   [junit4]   2> 4785344 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4785345 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 75
   [junit4]   2> 4785347 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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=1
   [junit4]   2> 4785348 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4785348 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4785349 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4785349 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4785350 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 4785354 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 1
   [junit4]   2> 4785356 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4785356 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@44d43044
   [junit4]   2> 4785388 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4e0ea72c[collection1] main]
   [junit4]   2> 4785388 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4785388 INFO  (searcherExecutor-9887-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4e0ea72c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(6.6.0):C1)
 Uninverting(_6(6.6.0):C1) Uninverting(_7(6.6.0):C1)))}
   [junit4]   2> 4785388 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 32
   [junit4]   2> 4785389 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4785390 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4785391 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4785391 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4785392 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4785393 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 4785399 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[4]} 0 4
   [junit4]   2> 4785399 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4785399 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@44d43044
   [junit4]   2> 4785461 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@745ea211[collection1] main]
   [junit4]   2> 4785462 INFO  (searcherExecutor-9887-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@745ea211[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> 4785463 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4785463 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 63
   [junit4]   2> 4785464 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_l_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4785464 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_l_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4785465 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4785466 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4785467 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_l_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4785468 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 4785470 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 1
   [junit4]   2> 4785470 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4785470 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@44d43044
   [junit4]   2> 4785582 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2a0dfd03[collection1] main]
   [junit4]   2> 4785584 INFO  (searcherExecutor-9887-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2a0dfd03[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> 4785585 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4785585 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 114
   [junit4]   2> 4785591 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_f_dvo&wt=xml} hits=1 status=0 QTime=5
   [junit4]   2> 4785600 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_f_dvo,*&wt=xml} hits=1 status=0 QTime=2
   [junit4]   2> 4785601 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4785601 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4785602 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_f_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4785603 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 4785606 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[6]} 0 1
   [junit4]   2> 4785607 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4785607 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@44d43044
   [junit4]   2> 4785660 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@26b54e9a[collection1] main]
   [junit4]   2> 4785661 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4785661 INFO  (searcherExecutor-9887-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@26b54e9a[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> 4785661 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 54
   [junit4]   2> 4785662 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4785664 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4785665 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4785668 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4785669 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4785670 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 4785673 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[7]} 0 1
   [junit4]   2> 4785674 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4785674 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@44d43044
   [junit4]   2> 4785713 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4d887c1e[collection1] main]
   [junit4]   2> 4785714 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4785714 INFO  (searcherExecutor-9887-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4d887c1e[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> 4785714 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 40
   [junit4]   2> 4785715 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_s_dv&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4785716 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_s_dv,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4785716 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4785717 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] 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> 4785718 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_s_dv,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4785718 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 4785720 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[8]} 0 1
   [junit4]   2> 4785721 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4785721 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@44d43044
   [junit4]   2> 4785772 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@99d6a46[collection1] main]
   [junit4]   2> 4785772 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E7F98B92BAB3A8DE])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4785773 INFO  (searcherExecutor-9887-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@99d6a46[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) 
Uni

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

t4]   2> 4791890 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[E7F98B92BAB3A8DE]) 
[    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 4791890 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[E7F98B92BAB3A8DE]) 
[    ] 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> 4791890 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[E7F98B92BAB3A8DE]) 
[    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 4791890 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[E7F98B92BAB3A8DE]) 
[    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1217991663
   [junit4]   2> 4791891 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[E7F98B92BAB3A8DE]) 
[    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 4791891 INFO  (coreCloseExecutor-9903-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@116f33f3
   [junit4]   2> 4791893 INFO  (coreCloseExecutor-9903-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 4791916 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[E7F98B92BAB3A8DE]) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 4791918 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[E7F98B92BAB3A8DE]) 
[    ] o.a.s.SolrTestCaseJ4 ###Ending testMultivaluedOrdering
   [junit4]   2> 4791932 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[E7F98B92BAB3A8DE]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E7F98B92BAB3A8DE-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): {}, 
docValues:{}, maxPointsInLeafNode=371, maxMBSortInHeap=7.9548696279166276, 
sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=uk-UA, 
timezone=Pacific/Nauru
   [junit4]   2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_121 
(64-bit)/cpus=3,threads=1,free=174948768,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [TestConfigSetsAPIZkFailure, 
TestDownShardTolerantSearch, DistributedSpellCheckComponentTest, 
UniqFieldsUpdateProcessorFactoryTest, SegmentsInfoRequestHandlerTest, 
TestExportWriter, PrimitiveFieldTypeTest, TestBulkSchemaConcurrent, 
MigrateRouteKeyTest, SmileWriterTest, TestBinaryResponseWriter, 
XsltUpdateRequestHandlerTest, TestTolerantSearch, TestReRankQParserPlugin, 
SoftAutoCommitTest, ResponseLogComponentTest, PeerSyncReplicationTest, 
TestLFUCache, InfixSuggestersTest, TestConfigOverlay, 
BaseCdcrDistributedZkTest, InfoHandlerTest, ExplicitHLLTest, FileUtilsTest, 
OverriddenZkACLAndCredentialsProvidersTest, 
PreAnalyzedFieldManagedSchemaCloudTest, TestScoreJoinQPNoScore, 
TermsComponentTest, TestUpdate, TestPostingsSolrHighlighter, 
TestSolrDynamicMBean, AnalysisAfterCoreReloadTest, TestAuthenticationFramework, 
WrapperMergePolicyFactoryTest, TestFunctionQuery, CurrencyFieldXmlFileTest, 
BlockJoinFacetSimpleTest, TestSort, TestSolrCloudWithDelegationTokens, 
TestElisionMultitermQuery, SolrMetricReporterTest, SimplePostToolTest, 
TimeZoneUtilsTest, TestSolrFieldCacheMBean, TestReplicationHandlerBackup, 
TemplateUpdateProcessorTest, TestPseudoReturnFields, TestConfigSetProperties, 
SchemaVersionSpecificBehaviorTest, RequestLoggingTest, 
OverseerModifyCollectionTest, TestRestManager, TestPhraseSuggestions, 
SystemInfoHandlerTest, TestStressVersions, TestMiniSolrCloudCluster, 
TestLRUCache, TestSubQueryTransformerCrossCore, TestCollationField, 
DirectSolrConnectionTest, TestWordDelimiterFilterFactory, 
ChaosMonkeyNothingIsSafeTest, BigEndianAscendingWordSerializerTest, 
TestEmbeddedSolrServerConstructors, PKIAuthenticationIntegrationTest, 
DeleteReplicaTest, TestSolrQueryParserDefaultOperatorResource, 
ExitableDirectoryReaderTest, SampleTest, TestRandomFlRTGCloud, 
TestUnifiedSolrHighlighter, DistributedQueryElevationComponentTest, 
LeaderFailoverAfterPartitionTest, TestLegacyFieldCache, TestStressUserVersions, 
RecoveryZkTest, CloneFieldUpdateProcessorFactoryTest, TestConfigReload, 
QueryElevationComponentTest, TestApiFramework, AssignTest, 
TestManagedSchemaAPI, DistributedQueueTest, DirectUpdateHandlerTest, 
RemoteQueryErrorTest, DirectSolrSpellCheckerTest, 
CdcrReplicationDistributedZkTest, TestHdfsUpdateLog, 
IndexBasedSpellCheckerTest, TestStressInPlaceUpdates, 
TestSchemalessBufferedUpdates, TestCloudInspectUtil, CdcrRequestHandlerTest, 
TestExtendedDismaxParser, NumericFieldsTest, TestSchemaVersionResource, 
TestShortCircuitedRequests, TestPartialUpdateDeduplication, TestRawTransformer, 
CollectionStateFormat2Test, ConjunctionSolrSpellCheckerTest, 
V2ApiIntegrationTest, TestOverriddenPrefixQueryForCustomFieldType, 
StatelessScriptUpdateProcessorFactoryTest, EnumFieldTest, 
TestAnalyzeInfixSuggestions, TestSolrCloudSnapshots, HdfsDirectoryTest, 
TestLocalFSCloudBackupRestore, JSONWriterTest, DocValuesTest, 
XmlUpdateRequestHandlerTest, TestFieldCacheSort, TestCorePropertiesReload, 
TestJsonRequest, TestObjectReleaseTracker, TestUseDocValuesAsStored2, 
RollingRestartTest, TestSolrCLIRunExample, TestMaxScoreQueryParser, 
TestMinMaxOnMultiValuedField, TestRandomDVFaceting, CdcrUpdateLogTest, 
TestNRTOpen, PrimUtilsTest, DisMaxRequestHandlerTest, 
BlockJoinFacetDistribTest, TestLeaderElectionZkExpiry, 
HdfsChaosMonkeySafeLeaderTest, JSONWriterTest, AnalyticsQueryTest, 
OverseerStatusTest, TestHdfsCloudBackupRestore, 
DocumentAnalysisRequestHandlerTest, HardAutoCommitTest, 
TestFileDictionaryLookup, IgnoreCommitOptimizeUpdateProcessorFactoryTest, 
SolrGangliaReporterTest, SyncSliceTest, BadComponentTest, 
PeerSyncWithIndexFingerprintCachingTest, TestFieldCacheVsDocValues, 
TestManagedSynonymFilterFactory, HdfsRecoveryZkTest, ZkNodePropsTest, 
LeaderElectionTest, CollectionsAPIAsyncDistributedZkTest, 
HdfsBasicDistributedZkTest, TestCursorMarkWithoutUniqueKey, ActionThrottleTest, 
PluginInfoTest, TestNumericTerms64, TestPivotHelperCode, 
ConcurrentDeleteAndCreateCollectionTest, TestHighlightDedupGrouping, 
SolrCoreTest, TestSolrConfigHandlerConcurrent, 
DeleteLastCustomShardedReplicaTest, SolrPluginUtilsTest, TestSolr4Spatial2, 
PropertiesRequestHandlerTest, TestCustomSort, TestPKIAuthenticationPlugin, 
JavabinLoaderTest, TestConfigSetsAPI, ShufflingReplicaListTransformerTest, 
TestManagedResource, PreAnalyzedUpdateProcessorTest, ZkCLITest, 
BooleanFieldTest, ZkStateWriterTest, RankQueryTest, BasicDistributedZkTest, 
UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, 
LeaderElectionIntegrationTest, ShardRoutingTest, BasicZkTest, 
FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, ShardRoutingCustomTest, 
TestDistributedSearch, PeerSyncTest, TestBadConfig, HighlighterTest, 
CurrencyFieldOpenExchangeTest, SuggesterFSTTest, TestFoldingMultitermQuery, 
SuggesterTest, SpatialFilterTest, PolyFieldTest, 
SolrCoreCheckLockOnStartupTest, QueryEqualityTest, 
DefaultValueUpdateProcessorTest, TestRemoteStreaming, TestSolrDeletionPolicy1, 
CacheHeaderTest, StandardRequestHandlerTest, TestReversedWildcardFilterFactory, 
TestQueryTypes, FileBasedSpellCheckerTest, PathHierarchyTokenizerFactoryTest, 
MoreLikeThisHandlerTest, FieldAnalysisRequestHandlerTest, TestSolrQueryParser, 
LoggingHandlerTest, TestJmxIntegration, QueryParsingTest, 
TestAnalyzedSuggestions, CSVRequestHandlerTest, TestComponentsName, 
SOLR749Test, UpdateParamsTest, AlternateDirectoryTest, TestSolrIndexConfig, 
TestQuerySenderNoQuery, SolrIndexConfigTest, TestStressRecovery, 
CloudExitableDirectoryReaderTest, CollectionsAPISolrJTest, 
DeleteInactiveReplicaTest, DistribCursorPagingTest, 
DistribJoinFromCollectionTest, DocValuesNotIndexedTest, ForceLeaderTest, 
LeaderFailureAfterFreshStartTest, LeaderInitiatedRecoveryOnShardRestartTest, 
MoveReplicaTest, ReplaceNodeTest, SharedFSAutoReplicaFailoverTest, 
SimpleCollectionCreateDeleteTest, SolrCloudExampleTest, 
TestCloudPseudoReturnFields, TestCloudRecovery, TestDistribDocBasedVersion, 
TestMiniSolrCloudClusterSSL, TestRequestForwarding, TestSSLRandomization, 
TestSizeLimitedDistributedMap, TestStressCloudBlindAtomicUpdates, 
TestTolerantUpdateProcessorRandomCloud, TlogReplayBufferedWhileIndexingTest, 
TriLevelCompositeIdRoutingTest, HdfsChaosMonkeyNothingIsSafeTest, 
HdfsNNFailoverTest, HdfsRecoverLeaseTest, HdfsSyncSliceTest, 
HdfsThreadLeakTest, HdfsTlogReplayBufferedWhileIndexingTest, 
HdfsWriteToMultipleCollectionsTest, StressHdfsTest, TestClusterStateMutator, 
RuleEngineTest, RulesTest, BlobRepositoryCloudTest, TestConfigSets, 
TestDynamicLoading, TestSQLHandlerNonCloud, TestSolrConfigHandlerCloud, 
DistributedFacetPivotLongTailTest, DistributedSuggestComponentTest, 
HighlighterMaxOffsetTest, JvmMetricsTest, SolrCoreMetricManagerTest, 
SolrMetricsIntegrationTest, SolrGraphiteReporterTest, SolrJmxReporterTest, 
TestIntervalFaceting, TestMacroExpander, TestBinaryResponseWriter, 
TestCustomDocTransformer, TestGeoJSONResponseWriter, TestGraphMLResponseWriter, 
TestPushWriter, TestRawResponseWriter, TestSolrQueryResponse, 
TestChildDocTransformer, TestSubQueryTransformer, 
TestSubQueryTransformerDistrib, TestManagedResourceStorage, 
TestClassNameShortening, TestDefaultSearchFieldResource, 
TestDynamicFieldResource, TestFieldResource, TestFieldTypeResource, 
TestSchemaNameResource, TestSchemaResource, TestSchemaSimilarityResource, 
TestSerializedLuceneMatchVersion, TestUniqueKeyFieldResource, BadCopyFieldTest, 
ChangedSchemaMergeTest, SpatialRPTFieldTypeTest, TestCollationFieldDocValues, 
TestPointFields, TestUseDocValuesAsStored]
   [junit4] Completed [632/704 (1!)] on J0 in 10.01s, 6 tests, 1 error <<< 
FAILURES!

[...truncated 43610 lines...]

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

Reply via email to