Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Solaris/869/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC

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

Error Message:
mismatch: 'myid1'!='myid' @ response/docs/[0]/id

Stack Trace:
java.lang.RuntimeException: mismatch: 'myid1'!='myid' @ response/docs/[0]/id
        at 
__randomizedtesting.SeedInfo.seed([DDCAC45B8431E0B0:EFE0C3C57CCFC469]:0)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:983)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:930)
        at 
org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults(TestUseDocValuesAsStored.java:243)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 13055 lines...]
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4]   2> Creating dataDir: 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_DDCAC45B8431E0B0-001/init-core-data-001
   [junit4]   2> 2600358 WARN  
(SUITE-TestUseDocValuesAsStored-seed#[DDCAC45B8431E0B0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=15 numCloses=15
   [junit4]   2> 2600358 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[DDCAC45B8431E0B0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 2600359 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[DDCAC45B8431E0B0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 2600361 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.SolrTestCaseJ4 ###Starting testMultivaluedOrdering
   [junit4]   2> 2600364 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 2600372 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 2600378 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] 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> 2600380 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 2600386 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 2600387 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_DDCAC45B8431E0B0-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 2600388 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_DDCAC45B8431E0B0-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml
 to 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_DDCAC45B8431E0B0-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 2600390 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 2600425 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29fb6780
   [junit4]   2> 2600427 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29fb6780
   [junit4]   2> 2600427 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29fb6780
   [junit4]   2> 2600436 INFO  (coreLoadExecutor-9388-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 2600440 INFO  (coreLoadExecutor-9388-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 2600446 INFO  (coreLoadExecutor-9388-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 2600446 INFO  (coreLoadExecutor-9388-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_DDCAC45B8431E0B0-001/tempDir-001/collection1,
 trusted=true
   [junit4]   2> 2600446 INFO  (coreLoadExecutor-9388-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@29fb6780
   [junit4]   2> 2600446 INFO  (coreLoadExecutor-9388-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2600446 INFO  (coreLoadExecutor-9388-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_DDCAC45B8431E0B0-001/tempDir-001/collection1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_DDCAC45B8431E0B0-001/init-core-data-001/]
   [junit4]   2> 2600448 INFO  (coreLoadExecutor-9388-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=44, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=99.583984375, 
floorSegmentMB=2.080078125, forceMergeDeletesPctAllowed=1.7713572293581092, 
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.49632633254354264
   [junit4]   2> 2600451 WARN  (coreLoadExecutor-9388-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> 2600488 INFO  (coreLoadExecutor-9388-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2600488 INFO  (coreLoadExecutor-9388-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2600488 INFO  (coreLoadExecutor-9388-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=19, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=77.662109375, 
floorSegmentMB=1.15234375, forceMergeDeletesPctAllowed=8.033853679270567, 
segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 2600489 INFO  (coreLoadExecutor-9388-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@13c3796b[collection1] main]
   [junit4]   2> 2600489 INFO  (coreLoadExecutor-9388-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_DDCAC45B8431E0B0-001/tempDir-001/collection1/conf
   [junit4]   2> 2600489 INFO  (coreLoadExecutor-9388-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2600489 INFO  
(searcherExecutor-9389-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@13c3796b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2600490 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 2600490 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 2600490 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] 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> 2600491 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[1]} 0 0
   [junit4]   2> 2600492 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[2]} 0 0
   [junit4]   2> 2600493 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[3]} 0 0
   [junit4]   2> 2600493 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[4]} 0 0
   [junit4]   2> 2600493 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2600493 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@2e9eb411
   [junit4]   2> 2600504 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@499aaea8[collection1] main]
   [junit4]   2> 2600505 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2600505 INFO  (searcherExecutor-9389-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@499aaea8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):C4)))}
   [junit4]   2> 2600505 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 11
   [junit4]   2> 2600505 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_mvt_dvt_st_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600506 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_mvt_dvt_sf_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600506 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_mvt_dvf_st_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600506 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_mvt_dvu_st_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600507 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 2600507 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] 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> 2600507 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 2600507 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1517700752
   [junit4]   2> 2600507 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 2600508 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 2600508 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 2600509 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 2600509 INFO  (coreCloseExecutor-9393-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@753f0276
   [junit4]   2> 2600510 INFO  (coreCloseExecutor-9393-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 2600512 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[DDCAC45B8431E0B0]) 
[    ] o.a.s.SolrTestCaseJ4 ###Ending testMultivaluedOrdering
   [junit4]   2> 2600514 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued
   [junit4]   2> 2600517 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 2600524 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 2600530 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600531 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 2600542 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 2600543 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_DDCAC45B8431E0B0-001/tempDir-002/collection1/conf/managed-schema
   [junit4]   2> 2600544 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_DDCAC45B8431E0B0-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml
 to 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_DDCAC45B8431E0B0-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 2600545 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 2600568 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29fb6780
   [junit4]   2> 2600571 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29fb6780
   [junit4]   2> 2600571 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29fb6780
   [junit4]   2> 2600580 INFO  (coreLoadExecutor-9400-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 2600586 INFO  (coreLoadExecutor-9400-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema 
name=test-non-stored-docvalues
   [junit4]   2> 2600591 INFO  (coreLoadExecutor-9400-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 
with uniqueid field id
   [junit4]   2> 2600592 INFO  (coreLoadExecutor-9400-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_DDCAC45B8431E0B0-001/tempDir-002/collection1,
 trusted=true
   [junit4]   2> 2600592 INFO  (coreLoadExecutor-9400-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@29fb6780
   [junit4]   2> 2600592 INFO  (coreLoadExecutor-9400-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2600592 INFO  (coreLoadExecutor-9400-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_DDCAC45B8431E0B0-001/tempDir-002/collection1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_DDCAC45B8431E0B0-001/init-core-data-001/]
   [junit4]   2> 2600596 WARN  (coreLoadExecutor-9400-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> 2600630 INFO  (coreLoadExecutor-9400-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2600630 INFO  (coreLoadExecutor-9400-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2600631 INFO  (coreLoadExecutor-9400-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=44, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=99.583984375, 
floorSegmentMB=2.080078125, forceMergeDeletesPctAllowed=1.7713572293581092, 
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.49632633254354264
   [junit4]   2> 2600633 INFO  (coreLoadExecutor-9400-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@65041455[collection1] main]
   [junit4]   2> 2600633 INFO  (coreLoadExecutor-9400-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_DDCAC45B8431E0B0-001/tempDir-002/collection1/conf
   [junit4]   2> 2600634 INFO  (coreLoadExecutor-9400-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2600634 INFO  
(searcherExecutor-9401-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@65041455[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):C4)))}
   [junit4]   2> 2600635 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 2600635 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 2600636 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600637 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 0
   [junit4]   2> 2600637 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2600637 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6a764694
   [junit4]   2> 2600646 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2c096704[collection1] main]
   [junit4]   2> 2600647 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2600647 INFO  (searcherExecutor-9401-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2c096704[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.7.0):C1)))}
   [junit4]   2> 2600648 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 11
   [junit4]   2> 2600648 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_ss_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600649 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_ss_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600650 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600651 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600651 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_ss_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600652 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600653 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 0
   [junit4]   2> 2600653 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2600654 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6a764694
   [junit4]   2> 2600666 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2d99355[collection1] main]
   [junit4]   2> 2600666 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2600666 INFO  (searcherExecutor-9401-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2d99355[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.7.0):C1)
 Uninverting(_2(6.7.0):C1)))}
   [junit4]   2> 2600667 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 13
   [junit4]   2> 2600667 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600668 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600669 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600670 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600671 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600691 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 2600693 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 0
   [junit4]   2> 2600693 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2600693 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6a764694
   [junit4]   2> 2600704 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@38ed2a0c[collection1] main]
   [junit4]   2> 2600704 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2600704 INFO  (searcherExecutor-9401-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@38ed2a0c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.7.0):C1)
 Uninverting(_2(6.7.0):C1) Uninverting(_3(6.7.0):C1)))}
   [junit4]   2> 2600704 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 11
   [junit4]   2> 2600705 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600705 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600705 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600706 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600706 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600706 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 2600707 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[4]} 0 0
   [junit4]   2> 2600707 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2600707 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6a764694
   [junit4]   2> 2600723 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7d9e211e[collection1] main]
   [junit4]   2> 2600724 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2600724 INFO  (searcherExecutor-9401-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7d9e211e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.7.0):C1)
 Uninverting(_2(6.7.0):C1) Uninverting(_3(6.7.0):C1) 
Uninverting(_4(6.7.0):C1)))}
   [junit4]   2> 2600724 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 17
   [junit4]   2> 2600724 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_l_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600725 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_l_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600725 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600725 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600726 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_l_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600726 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 2600727 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 0
   [junit4]   2> 2600727 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2600727 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6a764694
   [junit4]   2> 2600751 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@59473a97[collection1] main]
   [junit4]   2> 2600752 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2600754 INFO  (searcherExecutor-9401-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@59473a97[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.7.0):C1)
 Uninverting(_2(6.7.0):C1) Uninverting(_3(6.7.0):C1) Uninverting(_4(6.7.0):C1) 
Uninverting(_5(6.7.0):C1)))}
   [junit4]   2> 2600755 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 27
   [junit4]   2> 2600755 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600755 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600756 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600756 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600756 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600757 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 2600759 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[6]} 0 0
   [junit4]   2> 2600760 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2600760 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6a764694
   [junit4]   2> 2600776 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@79ad9d93[collection1] main]
   [junit4]   2> 2600776 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2600776 INFO  (searcherExecutor-9401-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@79ad9d93[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.7.0):C1)
 Uninverting(_2(6.7.0):C1) Uninverting(_3(6.7.0):C1) Uninverting(_4(6.7.0):C1) 
Uninverting(_5(6.7.0):C1) Uninverting(_6(6.7.0):C1)))}
   [junit4]   2> 2600777 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 17
   [junit4]   2> 2600778 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600780 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600781 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600782 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600783 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600784 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 2600786 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[7]} 0 0
   [junit4]   2> 2600787 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2600787 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6a764694
   [junit4]   2> 2600811 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@194658d1[collection1] main]
   [junit4]   2> 2600811 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2600811 INFO  (searcherExecutor-9401-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@194658d1[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.7.0):C1)
 Uninverting(_2(6.7.0):C1) Uninverting(_3(6.7.0):C1) Uninverting(_4(6.7.0):C1) 
Uninverting(_5(6.7.0):C1) Uninverting(_6(6.7.0):C1) Uninverting(_7(6.7.0):C1)))}
   [junit4]   2> 2600811 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 24
   [junit4]   2> 2600812 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600812 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600812 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600812 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600813 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600813 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 2600814 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[8]} 0 0
   [junit4]   2> 2600814 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2600814 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6a764694
   [junit4]   2> 2600824 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@26d0560e[collection1] main]
   [junit4]   2> 2600825 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2600825 INFO  (searcherExecutor-9401-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@26d0560e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.7.0):C1)
 Uninverting(_2(6.7.0):C1) Uninverting(_3(6.7.0):C1) Uninverting(_4(6.7.0):C1) 
Uninverting(_5(6.7.0):C1) Uninverting(_6(6.7.0):C1) Uninverting(_7(6.7.0):C1) 
Uninverting(_8(6.7.0):C1)))}
   [junit4]   2> 2600825 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 11
   [junit4]   2> 2600826 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_ss_dvo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600826 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_ss_dvo2,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600826 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600827 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600827 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_ss_dvo2,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600827 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
   [junit4]   2> 2600828 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[9]} 0 0
   [junit4]   2> 2600828 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2600828 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6a764694
   [junit4]   2> 2600839 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2d1362b9[collection1] main]
   [junit4]   2> 2600839 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2600840 INFO  (searcherExecutor-9401-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2d1362b9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.7.0):C1)
 Uninverting(_2(6.7.0):C1) Uninverting(_3(6.7.0):C1) Uninverting(_4(6.7.0):C1) 
Uninverting(_5(6.7.0):C1) Uninverting(_6(6.7.0):C1) Uninverting(_7(6.7.0):C1) 
Uninverting(_8(6.7.0):C1) Uninverting(_9(6.7.0):C1)))}
   [junit4]   2> 2600840 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 11
   [junit4]   2> 2600840 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=enum_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600840 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=enum_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600841 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600841 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600841 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=enum_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600841 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=9 status=0 QTime=0
   [junit4]   2> 2600842 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 2600842 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2600846 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[10]} 0 0
   [junit4]   2> 2600847 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2600847 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6a764694
   [junit4]   2> 2600853 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3d7bb64a[collection1] main]
   [junit4]   2> 2600854 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2600854 INFO  (searcherExecutor-9401-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3d7bb64a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(6.7.0):C1)))}
   [junit4]   2> 2600858 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 10
   [junit4]   2> 2600858 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=test_ss_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600859 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=test_ss_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600860 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600861 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600862 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=test_ss_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600862 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600864 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[11]} 0 0
   [junit4]   2> 2600864 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2600864 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6a764694
   [junit4]   2> 2600881 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5cd65e67[collection1] main]
   [junit4]   2> 2600882 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2600882 INFO  (searcherExecutor-9401-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5cd65e67[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(6.7.0):C1)
 Uninverting(_b(6.7.0):C1)))}
   [junit4]   2> 2600883 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 18
   [junit4]   2> 2600883 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:11&fl=test_i_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600884 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:11&fl=test_i_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600884 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:11&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600884 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:11&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600885 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:11&fl=test_i_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600885 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 2600886 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[12]} 0 0
   [junit4]   2> 2600886 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2600886 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6a764694
   [junit4]   2> 2600898 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@49d86580[collection1] main]
   [junit4]   2> 2600898 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2600898 INFO  (searcherExecutor-9401-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@49d86580[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(6.7.0):C1)
 Uninverting(_b(6.7.0):C1) Uninverting(_c(6.7.0):C1)))}
   [junit4]   2> 2600898 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 11
   [junit4]   2> 2600898 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=test_d_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600902 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=test_d_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600902 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600902 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600903 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=test_d_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600903 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 2600904 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[13]} 0 0
   [junit4]   2> 2600904 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2600904 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6a764694
   [junit4]   2> 2600912 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@39d6898c[collection1] main]
   [junit4]   2> 2600913 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2600913 INFO  (searcherExecutor-9401-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@39d6898c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(6.7.0):C1)
 Uninverting(_b(6.7.0):C1) Uninverting(_c(6.7.0):C1) 
Uninverting(_d(6.7.0):C1)))}
   [junit4]   2> 2600913 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 9
   [junit4]   2> 2600913 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:13&fl=test_l_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600914 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:13&fl=test_l_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600914 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:13&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600914 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:13&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600914 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:13&fl=test_l_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600915 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 2600915 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[14]} 0 0
   [junit4]   2> 2600915 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2600915 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6a764694
   [junit4]   2> 2600932 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1fbed277[collection1] main]
   [junit4]   2> 2600933 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2600933 INFO  (searcherExecutor-9401-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1fbed277[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(6.7.0):C1)
 Uninverting(_b(6.7.0):C1) Uninverting(_c(6.7.0):C1) Uninverting(_d(6.7.0):C1) 
Uninverting(_e(6.7.0):C1)))}
   [junit4]   2> 2600934 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 18
   [junit4]   2> 2600934 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_f_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600935 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_f_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600935 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600944 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600945 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_f_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600946 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 2600947 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[15]} 0 0
   [junit4]   2> 2600947 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2600947 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6a764694
   [junit4]   2> 2600966 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@48be2fc8[collection1] main]
   [junit4]   2> 2600966 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2600966 INFO  (searcherExecutor-9401-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@48be2fc8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(6.7.0):C1)
 Uninverting(_b(6.7.0):C1) Uninverting(_c(6.7.0):C1) Uninverting(_d(6.7.0):C1) 
Uninverting(_e(6.7.0):C1) Uninverting(_f(6.7.0):C1)))}
   [junit4]   2> 2600966 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 19
   [junit4]   2> 2600967 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:15&fl=test_dts_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600967 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:15&fl=test_dts_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600968 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:15&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600968 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:15&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600969 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:15&fl=test_dts_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600969 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 2600971 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[16]} 0 0
   [junit4]   2> 2600971 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2600971 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6a764694
   [junit4]   2> 2600982 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3ab340a8[collection1] main]
   [junit4]   2> 2600982 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2600983 INFO  (searcherExecutor-9401-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3ab340a8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(6.7.0):C1)
 Uninverting(_b(6.7.0):C1) Uninverting(_c(6.7.0):C1) Uninverting(_d(6.7.0):C1) 
Uninverting(_e(6.7.0):C1) Uninverting(_f(6.7.0):C1) Uninverting(_g(6.7.0):C1)))}
   [junit4]   2> 2600983 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 11
   [junit4]   2> 2600983 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:16&fl=test_s_dv&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600983 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:16&fl=test_s_dv,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600983 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:16&fl=*_dv&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600984 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:16&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600985 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:16&fl=test_s_dv,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600985 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 2600986 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[17]} 0 0
   [junit4]   2> 2600987 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2600987 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6a764694
   [junit4]   2> 2600998 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@77da2fee[collection1] main]
   [junit4]   2> 2600998 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2600998 INFO  (searcherExecutor-9401-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@77da2fee[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(6.7.0):C1)
 Uninverting(_b(6.7.0):C1) Uninverting(_c(6.7.0):C1) Uninverting(_d(6.7.0):C1) 
Uninverting(_e(6.7.0):C1) Uninverting(_f(6.7.0):C1) Uninverting(_g(6.7.0):C1) 
Uninverting(_h(6.7.0):C1)))}
   [junit4]   2> 2600998 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 11
   [junit4]   2> 2600999 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:17&fl=test_ss_dvo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600999 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:17&fl=test_ss_dvo2,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600999 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:17&fl=*vo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2600999 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:17&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2601000 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:17&fl=test_ss_dvo2,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2601000 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
   [junit4]   2> 2601001 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[18]} 0 0
   [junit4]   2> 2601001 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2601001 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6a764694
   [junit4]   2> 2601013 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6517e92e[collection1] main]
   [junit4]   2> 2601013 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2601013 INFO  (searcherExecutor-9401-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6517e92e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(6.7.0):C1)
 Uninverting(_b(6.7.0):C1) Uninverting(_c(6.7.0):C1) Uninverting(_d(6.7.0):C1) 
Uninverting(_e(6.7.0):C1) Uninverting(_f(6.7.0):C1) Uninverting(_g(6.7.0):C1) 
Uninverting(_h(6.7.0):C1) Uninverting(_i(6.7.0):C1)))}
   [junit4]   2> 2601013 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 12
   [junit4]   2> 2601014 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:18&fl=enum_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2601014 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:18&fl=enum_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2601014 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:18&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2601015 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:18&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2601015 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:18&fl=enum_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2601015 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=9 status=0 QTime=0
   [junit4]   2> 2601015 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 2601015 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] 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> 2601016 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[19]} 0 0
   [junit4]   2> 2601016 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2601016 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6a764694
   [junit4]   2> 2601025 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4272dbf3[collection1] main]
   [junit4]   2> 2601025 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2601026 INFO  (searcherExecutor-9401-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4272dbf3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_j(6.7.0):C1)))}
   [junit4]   2> 2601029 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 12
   [junit4]   2> 2601029 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:19&fl=test_ss_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2601030 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:19&fl=test_ss_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2601030 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:19&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2601030 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:19&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2601031 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:19&fl=test_ss_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2601031 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2601032 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[20]} 0 0
   [junit4]   2> 2601032 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2601032 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6a764694
   [junit4]   2> 2601039 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@8195dae[collection1] main]
   [junit4]   2> 2601040 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2601040 INFO  (searcherExecutor-9401-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@8195dae[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_j(6.7.0):C1)
 Uninverting(_k(6.7.0):C1)))}
   [junit4]   2> 2601040 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 8
   [junit4]   2> 2601040 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:20&fl=test_i_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2601041 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:20&fl=test_i_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2601041 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:20&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2601041 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:20&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2601042 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:20&fl=test_i_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2601042 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 2601042 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[21]} 0 0
   [junit4]   2> 2601043 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2601043 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6a764694
   [junit4]   2> 2601050 INFO  (TEST-TestUseDocValuesA

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

66 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 2602866 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=697344013
   [junit4]   2> 2602866 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 2602877 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 2602879 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 2602879 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 2602879 INFO  (coreCloseExecutor-9453-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@33101638
   [junit4]   2> 2602880 INFO  (coreCloseExecutor-9453-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 2602882 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[DDCAC45B8431E0B0])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testMultipleSearchResults
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestUseDocValuesAsStored -Dtests.method=testMultipleSearchResults 
-Dtests.seed=DDCAC45B8431E0B0 -Dtests.slow=true -Dtests.locale=es-CO 
-Dtests.timezone=PST -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.18s J0 | 
TestUseDocValuesAsStored.testMultipleSearchResults <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: mismatch: 
'myid1'!='myid' @ response/docs/[0]/id
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([DDCAC45B8431E0B0:EFE0C3C57CCFC469]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:983)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:930)
   [junit4]    >        at 
org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults(TestUseDocValuesAsStored.java:243)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 2602886 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[DDCAC45B8431E0B0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_DDCAC45B8431E0B0-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): {}, 
docValues:{}, maxPointsInLeafNode=1086, maxMBSortInHeap=6.486223747038284, 
sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=es-CO, 
timezone=PST
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_131 
(64-bit)/cpus=3,threads=1,free=258272368,total=532676608
   [junit4]   2> NOTE: All tests run in this JVM: 
[PeerSyncWithIndexFingerprintCachingTest, UUIDUpdateProcessorFallbackTest, 
TestSolrCLIRunExample, DocValuesTest, TestHashPartitioner, PolyFieldTest, 
LeaderInitiatedRecoveryOnCommitTest, DistributedFacetPivotSmallTest, 
TestDocTermOrds, BaseCdcrDistributedZkTest, TestPointFields, RulesTest, 
TestReloadAndDeleteDocs, PKIAuthenticationIntegrationTest, SyncSliceTest, 
TestJmxIntegration, TestFieldCacheVsDocValues, TestStressUserVersions, 
OpenExchangeRatesOrgProviderTest, CoreAdminRequestStatusTest, 
ReplicationFactorTest, PrimitiveFieldTypeTest, DocValuesNotIndexedTest, 
ZkCLITest, TestSimpleTextCodec, NumericFieldsTest, 
TestStressCloudBlindAtomicUpdates, HdfsUnloadDistributedZkTest, 
DistributedQueueTest, DisMaxRequestHandlerTest, TestCloudNestedDocsSort, 
CursorPagingTest, TestSQLHandlerNonCloud, SubstringBytesRefFilterTest, 
TestLRUCache, TestSha256AuthenticationProvider, 
TestImpersonationWithHadoopAuth, TestRTGBase, ActionThrottleTest, 
TestHttpShardHandlerFactory, RequestLoggingTest, AddBlockUpdateTest, 
CollectionsAPIAsyncDistributedZkTest, DistributedSuggestComponentTest, 
TestSchemaResource, JsonValidatorTest, TestLockTree, StressHdfsTest, 
TestSubQueryTransformerDistrib, TestLeaderInitiatedRecoveryThread, 
HdfsThreadLeakTest, SchemaVersionSpecificBehaviorTest, 
TestComplexPhraseLeadingWildcard, DateFieldTest, SolrRequestParserTest, 
CheckHdfsIndexTest, TestExactStatsCache, BitVectorTest, TestXmlQParser, 
DOMUtilTest, TestUseDocValuesAsStored2, TestXIncludeConfig, 
CustomCollectionTest, TestMultiWordSynonyms, TestCryptoKeys, 
TestPostingsSolrHighlighter, ZkNodePropsTest, 
OverriddenZkACLAndCredentialsProvidersTest, TestCoreContainer, SOLR749Test, 
TestSolrQueryParserDefaultOperatorResource, DirectoryFactoryTest, 
TestCharFilters, DirectSolrConnectionTest, InfoHandlerTest, TestReloadDeadlock, 
TermsComponentTest, TestCSVResponseWriter, MultiTermTest, 
DocExpirationUpdateProcessorFactoryTest, HardAutoCommitTest, 
SynonymTokenizerTest, FullHLLTest, TestSchemalessBufferedUpdates, 
TestCloudManagedSchema, TestSimpleQParserPlugin, TestLegacyFieldCache, 
TestRandomDVFaceting, TestHalfAndHalfDocValues, TestPayloadScoreQParserPlugin, 
TestFunctionQuery, TestConfigSetImmutable, DistributedSpellCheckComponentTest, 
TestPseudoReturnFields, V2ApiIntegrationTest, TestFaceting, 
TestIBSimilarityFactory, TestReplicaProperties, TestSolrCloudSnapshots, 
TestBulkSchemaAPI, TestStressVersions, 
ClassificationUpdateProcessorFactoryTest, TestTolerantSearch, 
TestSolrFieldCacheMBean, CdcrUpdateLogTest, RemoteQueryErrorTest, 
DocumentBuilderTest, FileUtilsTest, TestDistributedStatsComponentCardinality, 
TestComponentsName, SolrSlf4jReporterTest, TestReplicationHandlerBackup, 
TestSolr4Spatial2, HdfsCollectionsAPIDistributedZkTest, 
ParsingFieldUpdateProcessorsTest, TestSizeLimitedDistributedMap, 
TestExclusionRuleCollectionAccess, TestJsonRequest, PrimUtilsTest, 
DeleteReplicaTest, OutputWriterTest, TestScoreJoinQPNoScore, 
TestSolrDynamicMBean, TestAnalyzedSuggestions, 
AtomicUpdateProcessorFactoryTest, SmileWriterTest, TestSortByMinMaxFunction, 
TestLFUCache, ConjunctionSolrSpellCheckerTest, HLLUtilTest, 
TestCloudPivotFacet, IndexSchemaRuntimeFieldTest, MoveReplicaTest, 
CdcrReplicationDistributedZkTest, VersionInfoTest, RecoveryZkTest, 
SharedFSAutoReplicaFailoverTest, TestCSVLoader, TestSweetSpotSimilarityFactory, 
TestDFISimilarityFactory, DocumentAnalysisRequestHandlerTest, 
PingRequestHandlerTest, BlockJoinFacetSimpleTest, TestConfigSets, 
SaslZkACLProviderTest, SolrGraphiteReporterTest, AnalysisAfterCoreReloadTest, 
NodeMutatorTest, TestEmbeddedSolrServerConstructors, 
DistribDocExpirationUpdateProcessorTest, StandardRequestHandlerTest, 
XmlUpdateRequestHandlerTest, ChangedSchemaMergeTest, ClusterStateUpdateTest, 
HighlighterMaxOffsetTest, TestNamedUpdateProcessors, 
OverseerModifyCollectionTest, TestRestoreCore, DistributedMLTComponentTest, 
TestManagedSynonymFilterFactory, MigrateRouteKeyTest, OverseerTest, 
TimeZoneUtilsTest, BadCopyFieldTest, DocValuesMultiTest, SolrCoreTest, 
TestPKIAuthenticationPlugin, JvmMetricsTest, RankQueryTest, TestConfigReload, 
DirectUpdateHandlerTest, CdcrRequestHandlerTest, TestSegmentSorting, 
TestFilteredDocIdSet, TestHdfsUpdateLog, HdfsRecoverLeaseTest, 
FullSolrCloudDistribCmdsTest, TestRandomFaceting, LeaderElectionTest, 
TestZkChroot, TestDistributedSearch, TestBinaryResponseWriter, 
TestQuerySenderListener, UpdateParamsTest, AlternateDirectoryTest, 
TestSolrIndexConfig, TestQuerySenderNoQuery, ResponseLogComponentTest, 
SolrIndexConfigTest, BadComponentTest, TestStressRecovery, 
PreAnalyzedFieldTest, ClusterStateTest, SliceStateTest, SystemInfoHandlerTest, 
UUIDFieldTest, DistributedIntervalFacetingTest, AssignTest, CdcrBootstrapTest, 
CollectionStateFormat2Test, ConcurrentDeleteAndCreateCollectionTest, 
ConfigSetsAPITest, ConnectionManagerTest, CreateCollectionCleanupTest, 
DeleteInactiveReplicaTest, DeleteLastCustomShardedReplicaTest, 
DeleteStatusTest, ForceLeaderTest, LeaderFailoverAfterPartitionTest, 
LeaderInitiatedRecoveryOnShardRestartTest, MultiThreadedOCPTest, 
SharedFSAutoReplicaFailoverUtilsTest, SimpleCollectionCreateDeleteTest, 
SolrCloudExampleTest, TestAuthenticationFramework, TestCloudDeleteByQuery, 
TestCloudInspectUtil, TestCloudRecovery, TestCollectionAPI, 
TestConfigSetsAPIExclusivity, TestDeleteCollectionOnDownNodes, 
TestDistribDocBasedVersion, TestHdfsCloudBackupRestore, 
TestLeaderElectionZkExpiry, TestRandomRequestDistribution, 
TestRequestStatusCollectionAPI, TestSSLRandomization, 
TestSolrCloudWithDelegationTokens, TestSolrCloudWithKerberosAlt, 
TestSolrCloudWithSecureImpersonation, TestStressLiveNodes, 
TestTolerantUpdateProcessorRandomCloud, TlogReplayBufferedWhileIndexingTest, 
TriLevelCompositeIdRoutingTest, ConfigureRecoveryStrategyTest, 
ExitableDirectoryReaderTest, HdfsDirectoryFactoryTest, TestDynamicLoading, 
TestReqParamsAPI, TestSQLHandler, CoreMergeIndexesAdminHandlerTest, 
PropertiesRequestHandlerTest, SegmentsInfoRequestHandlerTest, 
StatsReloadRaceTest, DistributedExpandComponentTest, 
DistributedFacetPivotLargeTest, DistributedFacetPivotSmallAdvancedTest, 
DistributedQueryComponentCustomSortTest, ShufflingReplicaListTransformerTest, 
SpatialHeatmapFacetsTest, SmileWriterTest, TestIntervalFaceting, 
TestMacroExpander, TestBinaryResponseWriter, TestGeoJSONResponseWriter, 
TestPushWriter, TestRawTransformer, TestChildDocTransformer, TestRestManager, 
TestDynamicFieldCollectionResource, TestFieldResource, TestFieldTypeResource, 
TestSchemaVersionResource, TestManagedStopFilterFactory, DateRangeFieldTest, 
ManagedSchemaRoundRobinCloudTest, SpatialRPTFieldTypeTest, 
TestManagedSchemaAPI, TestUseDocValuesAsStored]
   [junit4] Completed [650/715 (1!)] on J0 in 2.54s, 6 tests, 1 error <<< 
FAILURES!

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

Reply via email to