Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.6-Linux/2/
Java: 32bit/jdk-9-ea+168 -server -XX:+UseG1GC

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

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

Stack Trace:
java.lang.RuntimeException: mismatch: 'myid1'!='myid' @ response/docs/[0]/id
        at 
__randomizedtesting.SeedInfo.seed([A1148D4E62AFDC3A:933E8AD09A51F8E3]: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 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:563)
        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 
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.base/java.lang.Thread.run(Thread.java:844)




Build Log:
[...truncated 13040 lines...]
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001/init-core-data-001
   [junit4]   2> 1651721 WARN  
(SUITE-TestUseDocValuesAsStored-seed#[A1148D4E62AFDC3A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=17 numCloses=17
   [junit4]   2> 1651721 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[A1148D4E62AFDC3A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 1651722 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[A1148D4E62AFDC3A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 1651723 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testUseDocValuesAsStoredFalse
   [junit4]   2> 1651725 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1651731 WARN  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, 
use <mergePolicyFactory> instead.
   [junit4]   2> 1651731 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1651735 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1651737 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1651742 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 1651742 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 1651768 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1651770 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1651791 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@360c4f
   [junit4]   2> 1651795 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@360c4f
   [junit4]   2> 1651796 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@360c4f
   [junit4]   2> 1651802 WARN  (coreLoadExecutor-7646-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 1651802 INFO  (coreLoadExecutor-7646-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1651807 INFO  (coreLoadExecutor-7646-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
   [junit4]   2> 1651812 INFO  (coreLoadExecutor-7646-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 1651812 INFO  (coreLoadExecutor-7646-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001/tempDir-001/collection1,
 trusted=true
   [junit4]   2> 1651812 INFO  (coreLoadExecutor-7646-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 
'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@360c4f
   [junit4]   2> 1651812 INFO  (coreLoadExecutor-7646-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1651812 INFO  (coreLoadExecutor-7646-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001/tempDir-001/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001/init-core-data-001/]
   [junit4]   2> 1651815 INFO  (coreLoadExecutor-7646-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=22, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=69.984375, 
floorSegmentMB=1.88671875, forceMergeDeletesPctAllowed=1.5001067639728205, 
segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1651858 WARN  (coreLoadExecutor-7646-thread-1) [    ] 
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> 1651889 INFO  (coreLoadExecutor-7646-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1651889 INFO  (coreLoadExecutor-7646-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1651889 INFO  (coreLoadExecutor-7646-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=27, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5738789100159462]
   [junit4]   2> 1651890 INFO  (coreLoadExecutor-7646-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1850bb4[collection1] main]
   [junit4]   2> 1651890 INFO  (coreLoadExecutor-7646-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001/tempDir-001/collection1/conf
   [junit4]   2> 1651890 INFO  (coreLoadExecutor-7646-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1651890 INFO  (searcherExecutor-7647-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1850bb4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1651891 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1651891 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid]} 0 0
   [junit4]   2> 1651892 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1651892 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@f58aa9
   [junit4]   2> 1651986 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b542c7[collection1] main]
   [junit4]   2> 1651987 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1651987 INFO  (searcherExecutor-7647-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1b542c7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1)))}
   [junit4]   2> 1651987 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 95
   [junit4]   2> 1651987 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1651988 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1651988 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid&fl=id,nonstored_dv_*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1651988 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid&fl=id,nonstored_dv_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1651988 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1651988 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1651988 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1651989 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=6096467
   [junit4]   2> 1651989 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1651989 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 1651990 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 1651990 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1651991 INFO  (coreCloseExecutor-7651-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@17c3439
   [junit4]   2> 1651991 INFO  (coreCloseExecutor-7651-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 1651993 INFO  
(TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testUseDocValuesAsStoredFalse
   [junit4]   2> 1651995 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testMultipleSearchResults
   [junit4]   2> 1651996 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1652000 WARN  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, 
use <mergePolicyFactory> instead.
   [junit4]   2> 1652000 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1652003 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1652004 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1652008 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 1652008 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001/tempDir-002/collection1/conf/managed-schema
   [junit4]   2> 1652016 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1652029 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1652048 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@360c4f
   [junit4]   2> 1652053 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@360c4f
   [junit4]   2> 1652053 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@360c4f
   [junit4]   2> 1652058 WARN  (coreLoadExecutor-7658-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 1652058 INFO  (coreLoadExecutor-7658-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1652063 INFO  (coreLoadExecutor-7658-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
   [junit4]   2> 1652068 INFO  (coreLoadExecutor-7658-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 1652068 INFO  (coreLoadExecutor-7658-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001/tempDir-002/collection1,
 trusted=true
   [junit4]   2> 1652068 INFO  (coreLoadExecutor-7658-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 
'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@360c4f
   [junit4]   2> 1652068 INFO  (coreLoadExecutor-7658-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1652068 INFO  (coreLoadExecutor-7658-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001/tempDir-002/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001/init-core-data-001/]
   [junit4]   2> 1652071 WARN  (coreLoadExecutor-7658-thread-1) [    ] 
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> 1652097 INFO  (coreLoadExecutor-7658-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1652097 INFO  (coreLoadExecutor-7658-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1652098 INFO  (coreLoadExecutor-7658-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=22, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=69.984375, 
floorSegmentMB=1.88671875, forceMergeDeletesPctAllowed=1.5001067639728205, 
segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1652098 INFO  (coreLoadExecutor-7658-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@61c550[collection1] main]
   [junit4]   2> 1652099 INFO  (coreLoadExecutor-7658-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001/tempDir-002/collection1/conf
   [junit4]   2> 1652099 INFO  (coreLoadExecutor-7658-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1652099 INFO  (searcherExecutor-7659-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@61c550[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1)))}
   [junit4]   2> 1652100 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1652101 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid1]} 0 0
   [junit4]   2> 1652101 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid2]} 0 0
   [junit4]   2> 1652101 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid3]} 0 0
   [junit4]   2> 1652101 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid4]} 0 0
   [junit4]   2> 1652101 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid5]} 0 0
   [junit4]   2> 1652102 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[myid6]} 0 0
   [junit4]   2> 1652102 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1652102 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4f986e
   [junit4]   2> 1652281 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1341755[collection1] main]
   [junit4]   2> 1652282 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1652282 INFO  (searcherExecutor-7659-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1341755[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1)
 Uninverting(_1(6.6.0):c6)))}
   [junit4]   2> 1652282 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 180
   [junit4]   2> 1652283 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:myid*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 1652283 ERROR 
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.SolrTestCaseJ4 query failed JSON validation. error=mismatch: 
'myid1'!='myid' @ response/docs/[0]/id
   [junit4]   2>  expected 
=/response/docs==[{'id':'myid1','test_is_dvo':[101,102,103]},{'id':'myid2','test_is_dvo':[201,202]},{'id':'myid3','test_is_dvo':[301,302,303,304]},{'id':'myid4','test_s_dvo':'hello','test_is_dvo':[401,402]},{'id':'myid5'},{'id':'myid6','test_s_dvo':'hello'}]
   [junit4]   2>  response = {
   [junit4]   2>   "responseHeader":{
   [junit4]   2>     "status":0,
   [junit4]   2>     "QTime":0},
   [junit4]   2>   "response":{"numFound":7,"start":0,"docs":[
   [junit4]   2>       {
   [junit4]   2>         "id":"myid"},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid1",
   [junit4]   2>         "test_is_dvo":[101,
   [junit4]   2>           102,
   [junit4]   2>           103]},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid2",
   [junit4]   2>         "test_is_dvo":[201,
   [junit4]   2>           202]},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid3",
   [junit4]   2>         "test_is_dvo":[301,
   [junit4]   2>           302,
   [junit4]   2>           303,
   [junit4]   2>           304]},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid4",
   [junit4]   2>         "test_s_dvo":"hello",
   [junit4]   2>         "test_is_dvo":[401,
   [junit4]   2>           402]},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid5"},
   [junit4]   2>       {
   [junit4]   2>         "id":"myid6",
   [junit4]   2>         "test_s_dvo":"hello"}]
   [junit4]   2>   }}
   [junit4]   2> 
   [junit4]   2>  request = q=id:myid*&fl=*&wt=xml
   [junit4]   2> 1652284 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1652284 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1652284 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1652284 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=21296746
   [junit4]   2> 1652284 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1652285 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 1652287 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 1652287 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1652288 INFO  (coreCloseExecutor-7663-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@cd487e
   [junit4]   2> 1652288 INFO  (coreCloseExecutor-7663-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 1652292 INFO  
(TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testMultipleSearchResults
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestUseDocValuesAsStored -Dtests.method=testMultipleSearchResults 
-Dtests.seed=A1148D4E62AFDC3A -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=sr-Cyrl-BA -Dtests.timezone=Asia/Kamchatka -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.30s J2 | 
TestUseDocValuesAsStored.testMultipleSearchResults <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: mismatch: 
'myid1'!='myid' @ response/docs/[0]/id
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([A1148D4E62AFDC3A:933E8AD09A51F8E3]: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.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]    >        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]    >        at 
java.base/java.lang.reflect.Method.invoke(Method.java:563)
   [junit4]    >        at java.base/java.lang.Thread.run(Thread.java:844)
   [junit4]   2> 1652295 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued
   [junit4]   2> 1652299 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1652303 WARN  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, 
use <mergePolicyFactory> instead.
   [junit4]   2> 1652304 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1652308 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1652310 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1652315 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 1652316 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001/tempDir-003/collection1/conf/managed-schema
   [junit4]   2> 1652346 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1652347 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1652368 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@360c4f
   [junit4]   2> 1652373 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@360c4f
   [junit4]   2> 1652373 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@360c4f
   [junit4]   2> 1652380 WARN  (coreLoadExecutor-7670-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 1652380 INFO  (coreLoadExecutor-7670-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1652385 INFO  (coreLoadExecutor-7670-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
   [junit4]   2> 1652390 INFO  (coreLoadExecutor-7670-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 1652390 INFO  (coreLoadExecutor-7670-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001/tempDir-003/collection1,
 trusted=true
   [junit4]   2> 1652390 INFO  (coreLoadExecutor-7670-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 
'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@360c4f
   [junit4]   2> 1652390 INFO  (coreLoadExecutor-7670-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1652390 INFO  (coreLoadExecutor-7670-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001/tempDir-003/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001/init-core-data-001/]
   [junit4]   2> 1652393 WARN  (coreLoadExecutor-7670-thread-1) [    ] 
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> 1652428 INFO  (coreLoadExecutor-7670-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1652428 INFO  (coreLoadExecutor-7670-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1652429 INFO  (coreLoadExecutor-7670-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=22, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=69.984375, 
floorSegmentMB=1.88671875, forceMergeDeletesPctAllowed=1.5001067639728205, 
segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1652431 INFO  (coreLoadExecutor-7670-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1f031e3[collection1] main]
   [junit4]   2> 1652431 INFO  (coreLoadExecutor-7670-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001/tempDir-003/collection1/conf
   [junit4]   2> 1652431 INFO  (coreLoadExecutor-7670-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1652431 INFO  (searcherExecutor-7671-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1f031e3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1)
 Uninverting(_1(6.6.0):c6)))}
   [junit4]   2> 1652432 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1652432 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1652433 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1652437 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 3
   [junit4]   2> 1652437 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1652437 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cad51
   [junit4]   2> 1652533 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1fd5c15[collection1] main]
   [junit4]   2> 1652534 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1652534 INFO  (searcherExecutor-7671-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1fd5c15[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):c1)))}
   [junit4]   2> 1652535 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 97
   [junit4]   2> 1652535 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1652536 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1652537 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1652551 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1652552 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1652553 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1652554 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 0
   [junit4]   2> 1652555 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1652555 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cad51
   [junit4]   2> 1652679 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@17d4cec[collection1] main]
   [junit4]   2> 1652679 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1652680 INFO  (searcherExecutor-7671-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@17d4cec[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):c1)
 Uninverting(_3(6.6.0):c1)))}
   [junit4]   2> 1652680 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 125
   [junit4]   2> 1652680 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_i_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1652681 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_i_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1652681 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1652681 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1652682 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_i_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1652682 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 1652683 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 0
   [junit4]   2> 1652683 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1652683 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cad51
   [junit4]   2> 1652853 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1220e7a[collection1] main]
   [junit4]   2> 1652854 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1652854 INFO  (searcherExecutor-7671-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1220e7a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):c1)
 Uninverting(_3(6.6.0):c1) Uninverting(_4(6.6.0):c1)))}
   [junit4]   2> 1652854 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 171
   [junit4]   2> 1652855 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1652855 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1652856 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1652856 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1652856 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1652856 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 1652857 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[4]} 0 0
   [junit4]   2> 1652857 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1652857 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cad51
   [junit4]   2> 1653073 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1f62816[collection1] main]
   [junit4]   2> 1653073 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1653073 INFO  (searcherExecutor-7671-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1f62816[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):c1)
 Uninverting(_3(6.6.0):c1) Uninverting(_4(6.6.0):c1) 
Uninverting(_5(6.6.0):c1)))}
   [junit4]   2> 1653074 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 216
   [junit4]   2> 1653074 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1653075 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1653075 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1653075 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1653076 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1653076 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 1653080 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 3
   [junit4]   2> 1653081 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1653081 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cad51
   [junit4]   2> 1653304 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@75c28a[collection1] main]
   [junit4]   2> 1653305 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1653305 INFO  (searcherExecutor-7671-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@75c28a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):c1)
 Uninverting(_3(6.6.0):c1) Uninverting(_4(6.6.0):c1) Uninverting(_5(6.6.0):c1) 
Uninverting(_6(6.6.0):c1)))}
   [junit4]   2> 1653305 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 224
   [junit4]   2> 1653306 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_fs_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1653307 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_fs_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1653308 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1653309 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1653315 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_fs_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1653316 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1653325 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[6]} 0 0
   [junit4]   2> 1653325 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1653325 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cad51
   [junit4]   2> 1653588 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1d85e8f[collection1] main]
   [junit4]   2> 1653588 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1653589 INFO  (searcherExecutor-7671-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1d85e8f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):c1)
 Uninverting(_3(6.6.0):c1) Uninverting(_4(6.6.0):c1) Uninverting(_5(6.6.0):c1) 
Uninverting(_6(6.6.0):c1) Uninverting(_7(6.6.0):c1)))}
   [junit4]   2> 1653589 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 263
   [junit4]   2> 1653589 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1653590 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1653590 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1653591 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1653591 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1653591 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 1653593 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[7]} 0 0
   [junit4]   2> 1653594 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1653594 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cad51
   [junit4]   2> 1653874 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@df7860[collection1] main]
   [junit4]   2> 1653875 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1653876 INFO  (searcherExecutor-7671-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@df7860[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):c1)
 Uninverting(_3(6.6.0):c1) Uninverting(_4(6.6.0):c1) Uninverting(_5(6.6.0):c1) 
Uninverting(_6(6.6.0):c1) Uninverting(_7(6.6.0):c1) Uninverting(_8(6.6.0):c1)))}
   [junit4]   2> 1653876 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 282
   [junit4]   2> 1653877 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1653878 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1653878 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1653878 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1653879 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1653879 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 1653880 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[8]} 0 0
   [junit4]   2> 1653880 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1653880 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cad51
   [junit4]   2> 1654006 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1d6b8d[collection1] main]
   [junit4]   2> 1654007 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1654011 INFO  (searcherExecutor-7671-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1d6b8d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):c1)
 Uninverting(_3(6.6.0):c1) Uninverting(_4(6.6.0):c1) Uninverting(_5(6.6.0):c1) 
Uninverting(_6(6.6.0):c1) Uninverting(_7(6.6.0):c1) Uninverting(_8(6.6.0):c1) 
Uninverting(_9(6.6.0):c1)))}
   [junit4]   2> 1654011 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 130
   [junit4]   2> 1654011 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654054 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654055 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654056 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654059 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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=1
   [junit4]   2> 1654060 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
   [junit4]   2> 1654062 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[9]} 0 0
   [junit4]   2> 1654062 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1654062 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cad51
   [junit4]   2> 1654124 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@cceac1[collection1] main]
   [junit4]   2> 1654125 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1654125 INFO  (searcherExecutor-7671-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@cceac1[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):c1)
 Uninverting(_3(6.6.0):c1) Uninverting(_4(6.6.0):c1) Uninverting(_5(6.6.0):c1) 
Uninverting(_6(6.6.0):c1) Uninverting(_7(6.6.0):c1) Uninverting(_8(6.6.0):c1) 
Uninverting(_9(6.6.0):c1) Uninverting(_a(6.6.0):c1)))}
   [junit4]   2> 1654125 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 62
   [junit4]   2> 1654125 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=enums_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1654126 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=enums_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1654127 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654128 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654129 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:9&fl=enums_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1654129 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=9 status=0 QTime=0
   [junit4]   2> 1654131 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1654131 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654131 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[10]} 0 0
   [junit4]   2> 1654132 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1654132 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cad51
   [junit4]   2> 1654157 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2f84d2[collection1] main]
   [junit4]   2> 1654157 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1654166 INFO  (searcherExecutor-7671-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2f84d2[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.6.0):c1)))}
   [junit4]   2> 1654167 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 35
   [junit4]   2> 1654168 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=test_s_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1654168 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=test_s_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1654168 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654169 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654169 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:10&fl=test_s_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1654169 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1654170 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[11]} 0 0
   [junit4]   2> 1654170 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1654170 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cad51
   [junit4]   2> 1654211 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ef5410[collection1] main]
   [junit4]   2> 1654213 INFO  (searcherExecutor-7671-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1ef5410[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.6.0):c1)
 Uninverting(_c(6.6.0):c1)))}
   [junit4]   2> 1654213 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1654213 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 43
   [junit4]   2> 1654214 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:11&fl=test_is_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1654215 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:11&fl=test_is_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1654215 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654216 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654221 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:11&fl=test_is_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1654222 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 1654223 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[12]} 0 0
   [junit4]   2> 1654223 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1654223 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cad51
   [junit4]   2> 1654297 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@17d2070[collection1] main]
   [junit4]   2> 1654298 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1654298 INFO  (searcherExecutor-7671-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@17d2070[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.6.0):c1)
 Uninverting(_c(6.6.0):c1) Uninverting(_d(6.6.0):c1)))}
   [junit4]   2> 1654299 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 75
   [junit4]   2> 1654299 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=test_ds_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1654300 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=test_ds_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1654302 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654311 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654312 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=test_ds_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1654313 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 1654320 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[13]} 0 1
   [junit4]   2> 1654320 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1654320 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cad51
   [junit4]   2> 1654363 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@ee9a25[collection1] main]
   [junit4]   2> 1654364 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1654364 INFO  (searcherExecutor-7671-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@ee9a25[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.6.0):c1)
 Uninverting(_c(6.6.0):c1) Uninverting(_d(6.6.0):c1) 
Uninverting(_e(6.6.0):c1)))}
   [junit4]   2> 1654364 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 43
   [junit4]   2> 1654364 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654365 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654365 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654366 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654366 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654366 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 1654367 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[14]} 0 0
   [junit4]   2> 1654367 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1654368 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cad51
   [junit4]   2> 1654409 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@623cb[collection1] main]
   [junit4]   2> 1654410 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1654410 INFO  (searcherExecutor-7671-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@623cb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.6.0):c1)
 Uninverting(_c(6.6.0):c1) Uninverting(_d(6.6.0):c1) Uninverting(_e(6.6.0):c1) 
Uninverting(_f(6.6.0):c1)))}
   [junit4]   2> 1654410 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 42
   [junit4]   2> 1654411 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_fs_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1654412 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_fs_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1654413 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654414 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654415 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_fs_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1654420 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1654422 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[15]} 0 0
   [junit4]   2> 1654423 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1654423 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cad51
   [junit4]   2> 1654507 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1e7f43f[collection1] main]
   [junit4]   2> 1654508 INFO  (searcherExecutor-7671-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1e7f43f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.6.0):c1)
 Uninverting(_c(6.6.0):c1) Uninverting(_d(6.6.0):c1) Uninverting(_e(6.6.0):c1) 
Uninverting(_f(6.6.0):c1) Uninverting(_g(6.6.0):c1)))}
   [junit4]   2> 1654508 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1654508 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 85
   [junit4]   2> 1654509 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654509 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654510 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654511 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654511 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654512 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 1654513 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[16]} 0 0
   [junit4]   2> 1654513 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1654513 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cad51
   [junit4]   2> 1654579 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1e27ddc[collection1] main]
   [junit4]   2> 1654580 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1654587 INFO  (searcherExecutor-7671-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1e27ddc[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.6.0):c1)
 Uninverting(_c(6.6.0):c1) Uninverting(_d(6.6.0):c1) Uninverting(_e(6.6.0):c1) 
Uninverting(_f(6.6.0):c1) Uninverting(_g(6.6.0):c1) Uninverting(_h(6.6.0):c1)))}
   [junit4]   2> 1654591 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 78
   [junit4]   2> 1654592 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:16&fl=test_ss_dv&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1654592 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:16&fl=test_ss_dv,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1654593 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A1148D4E62AFDC3A])
 [    ] 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> 1654593 INFO  (TEST

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

  [junit4]   2> 1670766 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A1148D4E62AFDC3A]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[xyz]} 0 0
   [junit4]   2> 1670767 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A1148D4E62AFDC3A]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1670767 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A1148D4E62AFDC3A]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@2c8c1
   [junit4]   2> 1670791 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A1148D4E62AFDC3A]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@ec9d5a[collection1] main]
   [junit4]   2> 1670792 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A1148D4E62AFDC3A]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1670792 INFO  (searcherExecutor-7707-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@ec9d5a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7n(6.6.0):c1)))}
   [junit4]   2> 1670792 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A1148D4E62AFDC3A]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 25
   [junit4]   2> 1670793 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A1148D4E62AFDC3A]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 1670793 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A1148D4E62AFDC3A]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1670793 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A1148D4E62AFDC3A]) [    ] 
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> 1670793 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A1148D4E62AFDC3A]) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1670793 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A1148D4E62AFDC3A]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=31072936
   [junit4]   2> 1670793 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A1148D4E62AFDC3A]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1670794 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A1148D4E62AFDC3A]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 1670795 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A1148D4E62AFDC3A]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 1670795 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A1148D4E62AFDC3A]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1670795 INFO  (coreCloseExecutor-7711-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@78656b
   [junit4]   2> 1670795 INFO  (coreCloseExecutor-7711-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 1670798 INFO  
(TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A1148D4E62AFDC3A]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testOnEmptyIndex
   [junit4]   2> 1670798 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[A1148D4E62AFDC3A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestUseDocValuesAsStored_A1148D4E62AFDC3A-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): {}, 
docValues:{}, maxPointsInLeafNode=1273, maxMBSortInHeap=5.031152230985216, 
sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=sr-Cyrl-BA, 
timezone=Asia/Kamchatka
   [junit4]   2> NOTE: Linux 4.10.0-21-generic i386/Oracle Corporation 9-ea 
(32-bit)/cpus=8,threads=1,free=189941328,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [TestMultiWordSynonyms, 
DocValuesMultiTest, TestReload, StatelessScriptUpdateProcessorFactoryTest, 
DistribDocExpirationUpdateProcessorTest, CoreAdminHandlerTest, 
DistributedFacetPivotWhiteBoxTest, PingRequestHandlerTest, 
TestLMJelinekMercerSimilarityFactory, ManagedSchemaRoundRobinCloudTest, 
TestFieldCache, AutoCommitTest, PeerSyncReplicationTest, TestLFUCache, 
RequestHandlersTest, DistributedSuggestComponentTest, TestStressUserVersions, 
SolrMetricManagerTest, TestPhraseSuggestions, 
BigEndianAscendingWordSerializerTest, CircularListTest, BJQParserTest, 
TestCoreDiscovery, DOMUtilTest, TestJmxMonitoredMap, TestManagedSchema, 
TestSimpleTextCodec, UpdateParamsTest, TestDistributedMissingSort, 
HdfsChaosMonkeyNothingIsSafeTest, AnalysisErrorHandlingTest, 
TestSolrCoreParser, CoreMergeIndexesAdminHandlerTest, TestNumericTerms64, 
TestDefaultStatsCache, ClassificationUpdateProcessorFactoryTest, 
PluginInfoTest, RollingRestartTest, TestDFRSimilarityFactory, JSONWriterTest, 
TestComplexPhraseQParserPlugin, HardAutoCommitTest, 
DistributedFacetPivotLongTailTest, TestCloudPseudoReturnFields, 
CollectionTooManyReplicasTest, TestBinaryResponseWriter, 
TestFieldTypeCollectionResource, TestExtendedDismaxParser, TestPointFields, 
SolrTestCaseJ4Test, BinaryUpdateRequestHandlerTest, 
SpellCheckCollatorWithCollapseTest, TestInPlaceUpdatesStandalone, 
TestDelegationWithHadoopAuth, TestJsonRequest, DirectUpdateHandlerOptimizeTest, 
CreateCollectionCleanupTest, TestWriterPerf, TestOnReconnectListenerSupport, 
CloneFieldUpdateProcessorFactoryTest, DirectUpdateHandlerTest, 
TestRequestStatusCollectionAPI, TestSolrJ, TestReversedWildcardFilterFactory, 
MigrateRouteKeyTest, TestSolrXml, TestHdfsBackupRestoreCore, BadComponentTest, 
TestRestoreCore, TestSlowCompositeReaderWrapper, StatsReloadRaceTest, 
TestDynamicFieldResource, CloudExitableDirectoryReaderTest, 
ParsingFieldUpdateProcessorsTest, TestCloudSchemaless, 
XmlUpdateRequestHandlerTest, TestSolrCloudWithKerberosAlt, TestPushWriter, 
TestExpandComponent, TestTolerantUpdateProcessorRandomCloud, 
SolrGangliaReporterTest, MinimalSchemaTest, TestCollectionAPI, 
MergeStrategyTest, TestNestedDocsSort, TestShortCircuitedRequests, 
DateRangeFieldTest, RecoveryZkTest, DistributedExpandComponentTest, 
LeaderFailureAfterFreshStartTest, TestLeaderInitiatedRecoveryThread, 
FieldMutatingUpdateProcessorTest, VMParamsZkACLAndCredentialsProvidersTest, 
QueryResultKeyTest, TestRecovery, TestRecoveryHdfs, TestStressReorder, 
TestManagedResourceStorage, TestClassNameShortening, TestAnalyzedSuggestions, 
MultiThreadedOCPTest, BlockJoinFacetSimpleTest, HdfsRestartWhileUpdatingTest, 
ActionThrottleTest, FullSolrCloudDistribCmdsTest, TestFilteredDocIdSet, 
ZkStateWriterTest, ReplicaListTransformerTest, TestHdfsCloudBackupRestore, 
TestFieldCacheWithThreads, SolrXmlInZkTest, TestSolrDeletionPolicy1, 
TestClassicSimilarityFactory, TestFieldCacheSort, TestConfig, 
BasicDistributedZk2Test, SyncSliceTest, LeaderElectionIntegrationTest, 
ClusterStateUpdateTest, LeaderElectionTest, ZkSolrClientTest, TestFaceting, 
TestHashPartitioner, DistributedSpellCheckComponentTest, 
TermVectorComponentDistributedTest, TestRealTimeGet, TestJoin, 
TestStressVersions, TestRangeQuery, TestAtomicUpdateErrorCases, 
QueryEqualityTest, DistanceFunctionTest, CacheHeaderTest, 
TestSurroundQueryParser, DisMaxRequestHandlerTest, StandardRequestHandlerTest, 
DocumentAnalysisRequestHandlerTest, PrimitiveFieldTypeTest, 
TestValueSourceCache, MoreLikeThisHandlerTest, FieldAnalysisRequestHandlerTest, 
IndexSchemaRuntimeFieldTest, SolrPluginUtilsTest, TestCSVResponseWriter, 
QueryParsingTest, TestPartialUpdateDeduplication, 
TestPHPSerializedResponseWriter, SOLR749Test, TestQuerySenderListener, 
TestQuerySenderNoQuery, ResponseLogComponentTest, SolrIndexConfigTest, 
SampleTest, TestSearchPerf, NumericFieldsTest, TestPostingsSolrHighlighter, 
TestLuceneMatchVersion, TestSweetSpotSimilarityFactory, TestFastWriter, 
TestFastOutputStream, DateMathParserTest, PrimUtilsTest, 
SpellingQueryConverterTest, TestUtils, ZkNodePropsTest, FileUtilsTest, 
TestHighlightDedupGrouping, TestTolerantSearch, TestPathTrie, 
TestEmbeddedSolrServerSchemaAPI, ConnectionReuseTest, 
CollectionsAPIAsyncDistributedZkTest, DistribCursorPagingTest, 
LeaderElectionContextKeyTest, LeaderInitiatedRecoveryOnShardRestartTest, 
OutOfBoxZkACLAndCredentialsProvidersTest, 
OverriddenZkACLAndCredentialsProvidersTest, 
OverseerCollectionConfigSetProcessorTest, OverseerStatusTest, ReplaceNodeTest, 
SharedFSAutoReplicaFailoverUtilsTest, SimpleCollectionCreateDeleteTest, 
TestAuthenticationFramework, TestCloudRecovery, TestConfigSetsAPIExclusivity, 
TestConfigSetsAPIZkFailure, TestDeleteCollectionOnDownNodes, 
TestDistribDocBasedVersion, TestDownShardTolerantSearch, 
TestExclusionRuleCollectionAccess, TestLocalFSCloudBackupRestore, 
TestMiniSolrCloudCluster, TestReplicaProperties, TestRequestForwarding, 
TestSolrCloudWithSecureImpersonation, TestStressInPlaceUpdates, 
TestSQLHandlerNonCloud, TestSolrConfigHandlerCloud, JavabinLoaderTest, 
CheckHdfsIndexTest, JvmMetricsTest, SolrCoreMetricManagerTest, 
SolrSlf4jReporterTest, SubstringBytesRefFilterTest, TestMacroExpander, 
SmileWriterTest, TestGraphMLResponseWriter, TestSolrQueryResponse, 
TestSubQueryTransformerDistrib, BadCopyFieldTest, BooleanFieldTest, 
ChangedSchemaMergeTest, EnumFieldTest, PreAnalyzedFieldManagedSchemaCloudTest, 
TestCollationFieldDocValues, TestManagedSchemaAPI, TestUseDocValuesAsStored]
   [junit4] Completed [642/711 (1!)] on J2 in 19.09s, 6 tests, 1 error <<< 
FAILURES!

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

Reply via email to