Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Solaris/234/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC

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

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
        at 
__randomizedtesting.SeedInfo.seed([A542CABFE8EDB91C:4B9FDE9F265C4FA0]:0)
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:947)
        at 
org.apache.solr.schema.TestUseDocValuesAsStored.doTest(TestUseDocValuesAsStored.java:367)
        at 
org.apache.solr.schema.TestUseDocValuesAsStored.testDuplicateMultiValued(TestUseDocValuesAsStored.java:172)
        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:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        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:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        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:748)
Caused by: java.lang.RuntimeException: REQUEST FAILED: 
xpath=//arr[@name='enums_dvo']/str[.='Not Available']
        xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int 
name="QTime">0</int></lst><result name="response" numFound="11" 
start="0"><doc><str name="id">myid1</str></doc><doc><str 
name="id">myid</str></doc><doc><str name="id">1</str><arr 
name="test_ss_dvo"><str>X</str><str>Y</str></arr></doc><doc><str 
name="id">2</str><arr 
name="test_ss_dv"><str>X</str><str>X</str><str>Y</str></arr></doc><doc><str 
name="id">3</str><arr 
name="test_ss_dvo2"><str>X</str><str>Y</str></arr></doc><doc><str 
name="id">4</str><arr 
name="test_is_dvo"><int>-666</int><int>42</int></arr></doc><doc><str 
name="id">5</str><arr 
name="test_fs_dvo"><float>-66.666</float><float>4.2</float></arr></doc><doc><str
 name="id">6</str><arr 
name="test_ls_dvo"><long>-6666666</long><long>420</long></arr></doc><doc><str 
name="id">7</str><arr 
name="test_ds_dvo"><double>-6.6666E-5</double><double>0.0042</double></arr></doc><doc><str
 name="id">8</str><arr 
name="test_dts_dvo"><date>1999-12-31T23:59:59Z</date><date>2016-07-04T03:02:01Z</date></arr></doc></result>
</response>

        request was:q=*:*&fl=*&wt=xml
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:940)
        ... 41 more




Build Log:
[...truncated 13270 lines...]
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4]   2> 318877 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[A542CABFE8EDB91C]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: 
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_A542CABFE8EDB91C-001/init-core-data-001
   [junit4]   2> 318878 WARN  
(SUITE-TestUseDocValuesAsStored-seed#[A542CABFE8EDB91C]-worker) [     ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=82 numCloses=82
   [junit4]   2> 318878 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[A542CABFE8EDB91C]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 318879 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[A542CABFE8EDB91C]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 318882 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.SolrTestCaseJ4 ###Starting testOnEmptyIndex
   [junit4]   2> 318885 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 318890 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.3.0
   [junit4]   2> 318896 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] 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> 318897 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 318902 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 318911 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_A542CABFE8EDB91C-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 318914 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed 
the non-managed schema 
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_A542CABFE8EDB91C-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml
 to 
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_A542CABFE8EDB91C-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 319612 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 319618 WARN  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@3841bf74[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 319618 WARN  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@3841bf74[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 319620 WARN  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@19edc83b[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 319620 WARN  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@19edc83b[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 319637 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 319637 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics 
history in memory.
   [junit4]   2> 319660 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@11d60489
   [junit4]   2> 319665 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@11d60489
   [junit4]   2> 319666 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@11d60489
   [junit4]   2> 319676 INFO  (coreLoadExecutor-1450-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.3.0
   [junit4]   2> 319685 INFO  (coreLoadExecutor-1450-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 319692 INFO  (coreLoadExecutor-1450-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 319692 INFO  (coreLoadExecutor-1450-thread-1) [    
x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_A542CABFE8EDB91C-001/tempDir-001/collection1,
 trusted=true
   [junit4]   2> 319693 INFO  (coreLoadExecutor-1450-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@11d60489
   [junit4]   2> 319693 INFO  (coreLoadExecutor-1450-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_A542CABFE8EDB91C-001/tempDir-001/collection1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_A542CABFE8EDB91C-001/init-core-data-001/]
   [junit4]   2> 319695 INFO  (coreLoadExecutor-1450-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=24, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4173488147485306]
   [junit4]   2> 319698 WARN  (coreLoadExecutor-1450-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> 319734 INFO  (coreLoadExecutor-1450-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 319734 INFO  (coreLoadExecutor-1450-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 319735 INFO  (coreLoadExecutor-1450-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=14, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=10.5048828125, 
floorSegmentMB=1.8330078125, forceMergeDeletesPctAllowed=2.379525845236362, 
segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5723516674810656, deletesPctAllowed=31.14263474676688
   [junit4]   2> 319735 INFO  (coreLoadExecutor-1450-thread-1) [    
x:collection1 ] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@70be501b[collection1] main]
   [junit4]   2> 319736 INFO  (coreLoadExecutor-1450-thread-1) [    
x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized 
to use dir: 
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_A542CABFE8EDB91C-001/tempDir-001/collection1/conf
   [junit4]   2> 319736 INFO  (coreLoadExecutor-1450-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 319737 INFO  
(searcherExecutor-1451-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@70be501b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 319739 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 319739 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 319740 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] 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> 319740 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 319740 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4d280cf7 commitCommandVersion:0
   [junit4]   2> 319744 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.s.SolrIndexSearcher Opening [Searcher@40d37f67[collection1] main]
   [junit4]   2> 319744 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 319745 INFO  (searcherExecutor-1451-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@40d37f67[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 319745 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 4
   [junit4]   2> 319745 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 319745 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 319745 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] 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> 319745 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] 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> 319759 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[xyz]} 0 13
   [junit4]   2> 319759 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 319759 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 319759 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] 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> 319759 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] 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> 319760 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 319760 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4d280cf7 commitCommandVersion:0
   [junit4]   2> 319769 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.s.SolrIndexSearcher Opening [Searcher@32c089ba[collection1] main]
   [junit4]   2> 319769 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 319771 INFO  (searcherExecutor-1451-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@32c089ba[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.3.0):C1:[diagnostics={os=SunOS,
 java.vendor=Oracle Corporation, java.version=1.8.0_201, 
java.vm.version=25.201-b09, lucene.version=8.3.0, os.arch=amd64, 
java.runtime.version=1.8.0_201-b09, source=flush, os.version=5.11, 
timestamp=1563035553846}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 319771 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 11
   [junit4]   2> 319771 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 319772 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 319772 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] 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> 319772 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] 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> 319773 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[xyz]} 0 0
   [junit4]   2> 319773 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 319773 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4d280cf7 commitCommandVersion:0
   [junit4]   2> 319780 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.s.SolrIndexSearcher Opening [Searcher@13af730[collection1] main]
   [junit4]   2> 319781 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 319781 INFO  (searcherExecutor-1451-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@13af730[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.3.0):C1:[diagnostics={os=SunOS,
 java.vendor=Oracle Corporation, java.version=1.8.0_201, 
java.vm.version=25.201-b09, lucene.version=8.3.0, os.arch=amd64, 
java.runtime.version=1.8.0_201-b09, source=flush, os.version=5.11, 
timestamp=1563035553859}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 319782 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 9
   [junit4]   2> 319783 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 319783 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 319783 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] 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> 319783 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 319783 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1266277782
   [junit4]   2> 319784 INFO  (coreCloseExecutor-1455-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@72c3e7e2
   [junit4]   2> 319784 INFO  (coreCloseExecutor-1455-thread-1) [    
x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=72c3e7e2
   [junit4]   2> 319784 INFO  (coreCloseExecutor-1455-thread-1) [    
x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@63c667f: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2364fff4
   [junit4]   2> 319791 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 319791 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@6945319: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@6052cc87
   [junit4]   2> 319795 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 319795 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@58371746: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@7a7b2066
   [junit4]   2> 319797 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 319797 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@409e5c0a: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@4472c762
   [junit4]   2> 319799 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A542CABFE8EDB91C]) [     
] o.a.s.SolrTestCaseJ4 ###Ending testOnEmptyIndex
   [junit4]   2> 319802 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.SolrTestCaseJ4 ###Starting testMultipleSearchResults
   [junit4]   2> 319804 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 319810 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.3.0
   [junit4]   2> 319815 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] 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> 319816 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 319820 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 319821 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_A542CABFE8EDB91C-001/tempDir-002/collection1/conf/managed-schema
   [junit4]   2> 319822 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_A542CABFE8EDB91C-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml
 to 
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_A542CABFE8EDB91C-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 321563 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 321565 WARN  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@537556e0[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 321565 WARN  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@537556e0[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 321572 WARN  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@3e480f5b[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 321572 WARN  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@3e480f5b[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 321603 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 321603 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics 
history in memory.
   [junit4]   2> 321633 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@11d60489
   [junit4]   2> 321642 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@11d60489
   [junit4]   2> 321642 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@11d60489
   [junit4]   2> 321657 INFO  (coreLoadExecutor-1465-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.3.0
   [junit4]   2> 321665 INFO  (coreLoadExecutor-1465-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 321670 INFO  (coreLoadExecutor-1465-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 321670 INFO  (coreLoadExecutor-1465-thread-1) [    
x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_A542CABFE8EDB91C-001/tempDir-002/collection1,
 trusted=true
   [junit4]   2> 321670 INFO  (coreLoadExecutor-1465-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@11d60489
   [junit4]   2> 321671 INFO  (coreLoadExecutor-1465-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_A542CABFE8EDB91C-001/tempDir-002/collection1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_A542CABFE8EDB91C-001/init-core-data-001/]
   [junit4]   2> 321673 WARN  (coreLoadExecutor-1465-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> 321733 INFO  (coreLoadExecutor-1465-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 321733 INFO  (coreLoadExecutor-1465-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 321734 INFO  (coreLoadExecutor-1465-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=24, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4173488147485306]
   [junit4]   2> 321736 INFO  (coreLoadExecutor-1465-thread-1) [    
x:collection1 ] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1701bd18[collection1] main]
   [junit4]   2> 321737 INFO  (coreLoadExecutor-1465-thread-1) [    
x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized 
to use dir: 
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_A542CABFE8EDB91C-001/tempDir-002/collection1/conf
   [junit4]   2> 321737 INFO  (coreLoadExecutor-1465-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 321738 INFO  
(searcherExecutor-1466-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1701bd18[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.3.0):C1:[diagnostics={java.vendor=Oracle
 Corporation, os=SunOS, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035553859}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 321740 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 321742 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid1]} 0 1
   [junit4]   2> 321742 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid2]} 0 0
   [junit4]   2> 321743 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid3]} 0 0
   [junit4]   2> 321743 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid4]} 0 0
   [junit4]   2> 321743 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid5]} 0 0
   [junit4]   2> 321743 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid6]} 0 0
   [junit4]   2> 321744 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 321744 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@48adf576 commitCommandVersion:0
   [junit4]   2> 321821 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c612d5b[collection1] main]
   [junit4]   2> 321821 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 321821 INFO  (searcherExecutor-1466-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1c612d5b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.3.0):C1:[diagnostics={java.vendor=Oracle
 Corporation, os=SunOS, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035553859}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_2(8.3.0):C6:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035555845}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 321821 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 77
   [junit4]   2> 321827 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid*&fl=*&wt=xml} hits=6 status=0 QTime=5
   [junit4]   2> 321830 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 321830 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] 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> 321830 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 321830 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1517740171
   [junit4]   2> 321831 INFO  (coreCloseExecutor-1470-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@651d21d5
   [junit4]   2> 321831 INFO  (coreCloseExecutor-1470-thread-1) [    
x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=651d21d5
   [junit4]   2> 321832 INFO  (coreCloseExecutor-1470-thread-1) [    
x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@9ad45f3: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@67204a94
   [junit4]   2> 321859 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.node, tag=null
   [junit4]   2> 321860 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@a2b7a6d: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@5df1d20d
   [junit4]   2> 321870 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jvm, tag=null
   [junit4]   2> 321870 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@7f02b341: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@7a7b2066
   [junit4]   2> 321874 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jetty, tag=null
   [junit4]   2> 321874 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@44caf6d4: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@4472c762
   [junit4]   2> 321879 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.SolrTestCaseJ4 ###Ending testMultipleSearchResults
   [junit4]   2> 321897 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued
   [junit4]   2> 321901 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 321911 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.3.0
   [junit4]   2> 321920 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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> 321923 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 321929 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 321930 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_A542CABFE8EDB91C-001/tempDir-003/collection1/conf/managed-schema
   [junit4]   2> 321931 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_A542CABFE8EDB91C-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml
 to 
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_A542CABFE8EDB91C-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 322549 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 322550 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@7d991cf[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 322550 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@7d991cf[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 322553 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@7d10417d[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 322553 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@7d10417d[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 322570 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 322570 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics 
history in memory.
   [junit4]   2> 322593 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@11d60489
   [junit4]   2> 322601 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@11d60489
   [junit4]   2> 322601 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@11d60489
   [junit4]   2> 322612 INFO  (coreLoadExecutor-1480-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.3.0
   [junit4]   2> 322621 INFO  (coreLoadExecutor-1480-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 322628 INFO  (coreLoadExecutor-1480-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 322628 INFO  (coreLoadExecutor-1480-thread-1) [    
x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_A542CABFE8EDB91C-001/tempDir-003/collection1,
 trusted=true
   [junit4]   2> 322628 INFO  (coreLoadExecutor-1480-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@11d60489
   [junit4]   2> 322628 INFO  (coreLoadExecutor-1480-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_A542CABFE8EDB91C-001/tempDir-003/collection1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_A542CABFE8EDB91C-001/init-core-data-001/]
   [junit4]   2> 322630 WARN  (coreLoadExecutor-1480-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> 322671 INFO  (coreLoadExecutor-1480-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 322671 INFO  (coreLoadExecutor-1480-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 322672 INFO  (coreLoadExecutor-1480-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=24, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4173488147485306]
   [junit4]   2> 322674 INFO  (coreLoadExecutor-1480-thread-1) [    
x:collection1 ] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@aa2fbd4[collection1] main]
   [junit4]   2> 322674 INFO  (coreLoadExecutor-1480-thread-1) [    
x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized 
to use dir: 
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_A542CABFE8EDB91C-001/tempDir-003/collection1/conf
   [junit4]   2> 322675 INFO  (coreLoadExecutor-1480-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 322676 INFO  
(searcherExecutor-1481-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@aa2fbd4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.3.0):C1:[diagnostics={java.vendor=Oracle
 Corporation, os=SunOS, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035553859}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_2(8.3.0):C6:[diagnostics={java.vendor=Oracle Corporation, 
os=SunOS, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035555845}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 322677 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 322678 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 322680 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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 3
   [junit4]   2> 322681 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 0
   [junit4]   2> 322681 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 322682 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4fc1f6d9 commitCommandVersion:0
   [junit4]   2> 322695 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@29c33b34[collection1] main]
   [junit4]   2> 322695 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 322696 INFO  (searcherExecutor-1481-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@29c33b34[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.3.0):C1:[diagnostics={os=SunOS,
 java.vendor=Oracle Corporation, java.version=1.8.0_201, 
java.vm.version=25.201-b09, lucene.version=8.3.0, os.arch=amd64, 
java.runtime.version=1.8.0_201-b09, source=flush, os.version=5.11, 
timestamp=1563035556768}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 322697 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 15
   [junit4]   2> 322698 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_s_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 322707 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_s_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 322709 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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> 322715 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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> 322715 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_s_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 322716 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 322716 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 0
   [junit4]   2> 322717 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 322717 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4fc1f6d9 commitCommandVersion:0
   [junit4]   2> 322728 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2ddd355d[collection1] main]
   [junit4]   2> 322729 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 322730 INFO  (searcherExecutor-1481-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2ddd355d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.3.0):C1:[diagnostics={os=SunOS,
 java.vendor=Oracle Corporation, java.version=1.8.0_201, 
java.vm.version=25.201-b09, lucene.version=8.3.0, os.arch=amd64, 
java.runtime.version=1.8.0_201-b09, source=flush, os.version=5.11, 
timestamp=1563035556768}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_4(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556804}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 322730 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 13
   [junit4]   2> 322731 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_is_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 322732 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_is_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 322733 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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> 322734 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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> 322734 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_is_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 322735 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 322736 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 0
   [junit4]   2> 322737 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 322737 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4fc1f6d9 commitCommandVersion:0
   [junit4]   2> 322745 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2fcbb21[collection1] main]
   [junit4]   2> 322746 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 322747 INFO  (searcherExecutor-1481-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2fcbb21[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.3.0):C1:[diagnostics={os=SunOS,
 java.vendor=Oracle Corporation, java.version=1.8.0_201, 
java.vm.version=25.201-b09, lucene.version=8.3.0, os.arch=amd64, 
java.runtime.version=1.8.0_201-b09, source=flush, os.version=5.11, 
timestamp=1563035556768}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_4(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556804}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556823}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 322747 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 10
   [junit4]   2> 322747 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_d_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 322748 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_d_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 322748 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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> 322749 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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> 322749 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_d_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 322749 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 322750 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[4]} 0 0
   [junit4]   2> 322750 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 322750 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4fc1f6d9 commitCommandVersion:0
   [junit4]   2> 322761 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@413eb907[collection1] main]
   [junit4]   2> 322762 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 322763 INFO  (searcherExecutor-1481-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@413eb907[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.3.0):C1:[diagnostics={os=SunOS,
 java.vendor=Oracle Corporation, java.version=1.8.0_201, 
java.vm.version=25.201-b09, lucene.version=8.3.0, os.arch=amd64, 
java.runtime.version=1.8.0_201-b09, source=flush, os.version=5.11, 
timestamp=1563035556768}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_4(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556804}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556823}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_6(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556837}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 322763 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 12
   [junit4]   2> 322764 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_ls_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 322765 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_ls_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 322765 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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> 322766 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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> 322767 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_ls_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 322767 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 322769 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 0
   [junit4]   2> 322769 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 322769 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4fc1f6d9 commitCommandVersion:0
   [junit4]   2> 322780 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2210331e[collection1] main]
   [junit4]   2> 322781 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 322782 INFO  (searcherExecutor-1481-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2210331e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.3.0):C1:[diagnostics={os=SunOS,
 java.vendor=Oracle Corporation, java.version=1.8.0_201, 
java.vm.version=25.201-b09, lucene.version=8.3.0, os.arch=amd64, 
java.runtime.version=1.8.0_201-b09, source=flush, os.version=5.11, 
timestamp=1563035556768}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_4(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556804}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556823}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_6(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556837}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_7(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556856}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 322782 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 13
   [junit4]   2> 322783 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_f_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 322783 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_f_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 322784 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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> 322784 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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> 322785 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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> 322785 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 322787 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[6]} 0 0
   [junit4]   2> 322787 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 322787 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4fc1f6d9 commitCommandVersion:0
   [junit4]   2> 322800 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@15cdd8f2[collection1] main]
   [junit4]   2> 322800 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 322801 INFO  (searcherExecutor-1481-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@15cdd8f2[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.3.0):C1:[diagnostics={os=SunOS,
 java.vendor=Oracle Corporation, java.version=1.8.0_201, 
java.vm.version=25.201-b09, lucene.version=8.3.0, os.arch=amd64, 
java.runtime.version=1.8.0_201-b09, source=flush, os.version=5.11, 
timestamp=1563035556768}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_4(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556804}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556823}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_6(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556837}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_7(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556856}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_8(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556874}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 322801 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 13
   [junit4]   2> 322801 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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> 322802 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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> 322802 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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> 322803 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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> 322804 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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> 322805 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 322807 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[7]} 0 0
   [junit4]   2> 322807 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 322808 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4fc1f6d9 commitCommandVersion:0
   [junit4]   2> 322821 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@32d1c797[collection1] main]
   [junit4]   2> 322822 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 322822 INFO  (searcherExecutor-1481-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@32d1c797[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.3.0):C1:[diagnostics={os=SunOS,
 java.vendor=Oracle Corporation, java.version=1.8.0_201, 
java.vm.version=25.201-b09, lucene.version=8.3.0, os.arch=amd64, 
java.runtime.version=1.8.0_201-b09, source=flush, os.version=5.11, 
timestamp=1563035556768}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_4(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556804}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556823}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_6(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556837}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_7(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556856}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_8(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556874}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_9(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556894}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 322823 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 15
   [junit4]   2> 322823 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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> 322824 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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> 322825 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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> 322825 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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> 322826 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] 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> 322827 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 322828 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[8]} 0 0
   [junit4]   2> 322829 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 322829 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4fc1f6d9 commitCommandVersion:0
   [junit4]   2> 322840 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@47c69aae[collection1] main]
   [junit4]   2> 322852 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 322853 INFO  (searcherExecutor-1481-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@47c69aae[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.3.0):C1:[diagnostics={os=SunOS,
 java.vendor=Oracle Corporation, java.version=1.8.0_201, 
java.vm.version=25.201-b09, lucene.version=8.3.0, os.arch=amd64, 
java.runtime.version=1.8.0_201-b09, source=flush, os.version=5.11, 
timestamp=1563035556768}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_4(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556804}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556823}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_6(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556837}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_7(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556856}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_8(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556874}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_9(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556894}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_a(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, 
timestamp=1563035556916}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 322853 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 24
   [junit4]   2> 322854 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_s_dvo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 322854 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_s_dvo2,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 322854 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=*vo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 322859 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 322860 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_s_dvo2,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 322860 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
   [junit4]   2> 322862 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[9]} 0 0
   [junit4]   2> 322862 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 322862 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4fc1f6d9 commitCommandVersion:0
   [junit4]   2> 322880 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@31aa2ae5[collection1] main]
   [junit4]   2> 322881 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A542CABFE8EDB91C])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 322882 INFO  (searcherExecutor-1481-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@31aa2ae5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.3.0):C1:[diagnostics={os=SunOS,
 java.vendor=Oracle Corporation, java.version=1.8.0_201, 
java.vm.version=25.201-b09, lucene.version=8.3.0, os.arch=amd64, 
java.runtime.version=1.8.0_201-b09, source=flush, os.version=5.11, 
timestamp=1563035556768}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_4(8.3.0):C1:[diagnostics={os=SunOS, java.vendor=Oracle 
Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, 
lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_201-b09, 
source=flush, os.version=5.11, timestamp=15630355

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

ting 5 suites with 2 JVMs.
   [junit4] 
   [junit4] Started J1 PID(9446@serv1-vm3).
   [junit4] Started J0 PID(9447@serv1-vm3).
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4] OK      5.19s J1 | TestUseDocValuesAsStored.testOnEmptyIndex
   [junit4] OK      0.57s J1 | 
TestUseDocValuesAsStored.testMultipleSearchResults
   [junit4] OK      5.72s J1 | 
TestUseDocValuesAsStored.testRandomSingleAndMultiValued
   [junit4] OK      0.55s J1 | TestUseDocValuesAsStored.testManagedSchema
   [junit4] OK      0.56s J1 | 
TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse
   [junit4] OK      2.28s J1 | TestUseDocValuesAsStored.testDuplicateMultiValued
   [junit4] OK      0.38s J1 | TestUseDocValuesAsStored.testMultivaluedOrdering
   [junit4] Completed [1/5] on J1 in 17.96s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4] OK      4.89s J0 | TestUseDocValuesAsStored.testOnEmptyIndex
   [junit4] OK      1.57s J0 | 
TestUseDocValuesAsStored.testMultipleSearchResults
   [junit4] OK      5.35s J0 | 
TestUseDocValuesAsStored.testRandomSingleAndMultiValued
   [junit4] OK      0.73s J0 | TestUseDocValuesAsStored.testManagedSchema
   [junit4] OK      0.46s J0 | 
TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse
   [junit4] OK      0.89s J0 | TestUseDocValuesAsStored.testDuplicateMultiValued
   [junit4] OK      2.66s J0 | TestUseDocValuesAsStored.testMultivaluedOrdering
   [junit4] Completed [2/5] on J0 in 19.14s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4] OK      0.84s J1 | TestUseDocValuesAsStored.testOnEmptyIndex
   [junit4] OK      0.76s J1 | 
TestUseDocValuesAsStored.testMultipleSearchResults
   [junit4] OK      3.96s J1 | 
TestUseDocValuesAsStored.testRandomSingleAndMultiValued
   [junit4] OK      0.66s J1 | TestUseDocValuesAsStored.testManagedSchema
   [junit4] OK      1.33s J1 | 
TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse
   [junit4] OK      0.72s J1 | TestUseDocValuesAsStored.testDuplicateMultiValued
   [junit4] OK      0.97s J1 | TestUseDocValuesAsStored.testMultivaluedOrdering
   [junit4] Completed [3/5] on J1 in 9.29s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4] OK      1.13s J0 | TestUseDocValuesAsStored.testOnEmptyIndex
   [junit4] OK      1.15s J0 | 
TestUseDocValuesAsStored.testMultipleSearchResults
   [junit4] OK      3.88s J0 | 
TestUseDocValuesAsStored.testRandomSingleAndMultiValued
   [junit4] OK      1.02s J0 | TestUseDocValuesAsStored.testManagedSchema
   [junit4] OK      0.67s J0 | 
TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse
   [junit4] OK      0.94s J0 | TestUseDocValuesAsStored.testDuplicateMultiValued
   [junit4] OK      3.00s J0 | TestUseDocValuesAsStored.testMultivaluedOrdering
   [junit4] Completed [4/5] on J0 in 12.08s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4] OK      0.50s J1 | TestUseDocValuesAsStored.testOnEmptyIndex
   [junit4] OK      1.21s J1 | 
TestUseDocValuesAsStored.testMultipleSearchResults
   [junit4] OK      4.54s J1 | 
TestUseDocValuesAsStored.testRandomSingleAndMultiValued
   [junit4] OK      0.57s J1 | TestUseDocValuesAsStored.testManagedSchema
   [junit4] OK      0.67s J1 | 
TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse
   [junit4] OK      1.08s J1 | TestUseDocValuesAsStored.testDuplicateMultiValued
   [junit4] OK      0.32s J1 | TestUseDocValuesAsStored.testMultivaluedOrdering
   [junit4] Completed [5/5] on J1 in 9.09s, 7 tests
   [junit4] 
   [junit4] JVM J0:     0.70 ..    32.72 =    32.03s
   [junit4] JVM J1:     0.69 ..    38.15 =    37.45s
   [junit4] Execution time total: 38 seconds
   [junit4] Tests summary: 5 suites, 35 tests
   [junit4] Could not remove temporary path: 
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J0
 (java.nio.file.DirectoryNotEmptyException: Remaining files: 
[/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J0/temp])
   [junit4] Could not remove temporary path: 
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1
 (java.nio.file.DirectoryNotEmptyException: Remaining files: 
[/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp])
     [echo] 5 slowest tests:
[junit4:tophints] 281.23s | 
org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest
[junit4:tophints] 231.81s | org.apache.solr.cloud.api.collections.ShardSplitTest
[junit4:tophints] 210.82s | org.apache.solr.search.facet.TestJsonFacets
[junit4:tophints] 166.83s | org.apache.solr.cloud.CollectionsAPISolrJTest
[junit4:tophints] 117.54s | org.apache.solr.update.SoftAutoCommitTest

-check-totals:

test-nocompile:

BUILD SUCCESSFUL
Total time: 40 seconds
[repro] Failures:
[repro]   0/5 failed: org.apache.solr.schema.TestUseDocValuesAsStored
[repro] Exiting with code 0
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting 
ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
java.lang.InterruptedException: no matches found within 10000
        at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2805)
        at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2684)
        at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2665)
        at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3052)
Also:   hudson.remoting.Channel$CallSiteStackTrace: Remote call to Solaris VBOX
                at 
hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1743)
                at 
hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
                at hudson.remoting.Channel.call(Channel.java:957)
                at hudson.FilePath.act(FilePath.java:1072)
                at hudson.FilePath.act(FilePath.java:1061)
                at hudson.FilePath.validateAntFileMask(FilePath.java:2663)
                at 
hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:248)
                at 
hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:79)
                at 
hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
                at 
hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:741)
                at 
hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
                at hudson.model.Build$BuildExecution.post2(Build.java:186)
                at 
hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
                at hudson.model.Run.execute(Run.java:1843)
                at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
                at 
hudson.model.ResourceController.execute(ResourceController.java:97)
                at hudson.model.Executor.run(Executor.java:429)
Caused: hudson.FilePath$TunneledInterruptedException
        at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3054)
        at hudson.remoting.UserRequest.perform(UserRequest.java:212)
        at hudson.remoting.UserRequest.perform(UserRequest.java:54)
        at hudson.remoting.Request$2.run(Request.java:369)
        at 
hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
        at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no 
matches found within 10000
        at hudson.FilePath.act(FilePath.java:1074)
        at hudson.FilePath.act(FilePath.java:1061)
        at hudson.FilePath.validateAntFileMask(FilePath.java:2663)
        at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:248)
        at 
hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:79)
        at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
        at 
hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:741)
        at 
hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
        at hudson.model.Build$BuildExecution.post2(Build.java:186)
        at 
hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
        at hudson.model.Run.execute(Run.java:1843)
        at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
        at hudson.model.ResourceController.execute(ResourceController.java:97)
        at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern 
"**/*.events,heapdumps/**,**/*_pid*.log". Configuration error?
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting 
ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
No credentials specified
Setting 
ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all 
warnings.
<Git Blamer> GIT_COMMIT=d91900a4a271829325a948aff874301979aba202, 
workspace=/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris
[WARNINGS] Computing warning deltas based on reference build #233
Recording test results
Setting 
ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting 
ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting 
ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting 
ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting 
ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting 
ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting 
ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to