[JENKINS-EA] Lucene-Solr-master-Linux (32bit/jdk-9-ea+168) - Build # 19561 - Still Unstable!

[Policeman Jenkins Server]

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/19561/
Java: 32bit/jdk-9-ea+168 -client -XX:+UseG1GC

2 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([92B7985AC914DA4C:A09D9FC431EAFE95]:0)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:984)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:931)
        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)


FAILED:  
org.apache.solr.update.processor.UpdateRequestProcessorFactoryTest.testRequestTimeUrp

Error Message:
No such processor Template

Stack Trace:
org.apache.solr.common.SolrException: No such processor Template
        at 
__randomizedtesting.SeedInfo.seed([92B7985AC914DA4C:3705D8926F5A13B8]:0)
        at 
org.apache.solr.update.processor.UpdateRequestProcessorChain.getReqProcessors(UpdateRequestProcessorChain.java:286)
        at 
org.apache.solr.update.processor.UpdateRequestProcessorChain.constructChain(UpdateRequestProcessorChain.java:235)
        at 
org.apache.solr.core.SolrCore.getUpdateProcessorChain(SolrCore.java:1446)
        at 
org.apache.solr.update.processor.UpdateRequestProcessorFactoryTest.testRequestTimeUrp(UpdateRequestProcessorFactoryTest.java:53)
        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 12061 lines...]
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/init-core-data-001
   [junit4]   2> 966857 WARN  
(SUITE-TestUseDocValuesAsStored-seed#[92B7985AC914DA4C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=8 numCloses=8
   [junit4]   2> 966858 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[92B7985AC914DA4C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 966858 INFO  
(SUITE-TestUseDocValuesAsStored-seed#[92B7985AC914DA4C]-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> 966860 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.SolrTestCaseJ4 ###Starting testManagedSchema
   [junit4]   2> 966863 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 966872 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 966878 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] 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> 966881 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 966888 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 966889 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 966971 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed 
the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 966994 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e0c71e
   [junit4]   2> 966999 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e0c71e
   [junit4]   2> 966999 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e0c71e
   [junit4]   2> 967006 INFO  (coreLoadExecutor-3710-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 967013 INFO  (coreLoadExecutor-3710-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
   [junit4]   2> 967018 INFO  (coreLoadExecutor-3710-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 967018 INFO  (coreLoadExecutor-3710-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/tempDir-001/collection1,
 trusted=true
   [junit4]   2> 967019 INFO  (coreLoadExecutor-3710-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@e0c71e
   [junit4]   2> 967019 INFO  (coreLoadExecutor-3710-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 967019 INFO  (coreLoadExecutor-3710-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/tempDir-001/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/init-core-data-001/]
   [junit4]   2> 967021 INFO  (coreLoadExecutor-3710-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=13, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=44.236328125, 
floorSegmentMB=0.8642578125, forceMergeDeletesPctAllowed=3.9089126134651977, 
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.3245942880460002
   [junit4]   2> 967128 WARN  (coreLoadExecutor-3710-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> 967159 INFO  (coreLoadExecutor-3710-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 967159 INFO  (coreLoadExecutor-3710-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 967160 INFO  (coreLoadExecutor-3710-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=37, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=91.8583984375, 
floorSegmentMB=1.8623046875, forceMergeDeletesPctAllowed=1.1863868435077995, 
segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4816567833291957
   [junit4]   2> 967161 INFO  (coreLoadExecutor-3710-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@d5151e[collection1] main]
   [junit4]   2> 967161 INFO  (coreLoadExecutor-3710-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/tempDir-001/collection1/conf
   [junit4]   2> 967162 INFO  (coreLoadExecutor-3710-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 967162 INFO  (searcherExecutor-3711-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@d5151e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 967163 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 967163 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 967233 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 967273 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 967273 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] 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> 967274 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[myid1]} 0 0
   [junit4]   2> 967275 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 967275 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1a91fdc commitCommandVersion:0
   [junit4]   2> 967921 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.s.SolrIndexSearcher Opening [Searcher@14f363d[collection1] main]
   [junit4]   2> 967922 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 967923 INFO  (searcherExecutor-3711-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@14f363d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1)))}
   [junit4]   2> 967923 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 648
   [junit4]   2> 967924 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] 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> 967925 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 967925 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] 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> 967925 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 967925 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=16155578
   [junit4]   2> 967925 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 967927 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 967928 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 967928 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 967929 INFO  (coreCloseExecutor-3715-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@ffd1a5
   [junit4]   2> 967930 INFO  (coreCloseExecutor-3715-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=16765349
   [junit4]   2> 967933 INFO  
(TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[92B7985AC914DA4C]) [    
] o.a.s.SolrTestCaseJ4 ###Ending testManagedSchema
   [junit4]   2> 967935 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.SolrTestCaseJ4 ###Starting testMultivaluedOrdering
   [junit4]   2> 967937 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 967943 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 967947 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] 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> 967949 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 967956 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 967956 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/tempDir-002/collection1/conf/managed-schema
   [junit4]   2> 968015 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 968051 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e0c71e
   [junit4]   2> 968058 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e0c71e
   [junit4]   2> 968058 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e0c71e
   [junit4]   2> 968069 INFO  (coreLoadExecutor-3722-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 968081 INFO  (coreLoadExecutor-3722-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
   [junit4]   2> 968087 INFO  (coreLoadExecutor-3722-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 968087 INFO  (coreLoadExecutor-3722-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/tempDir-002/collection1,
 trusted=true
   [junit4]   2> 968087 INFO  (coreLoadExecutor-3722-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@e0c71e
   [junit4]   2> 968087 INFO  (coreLoadExecutor-3722-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 968087 INFO  (coreLoadExecutor-3722-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/tempDir-002/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/init-core-data-001/]
   [junit4]   2> 968090 WARN  (coreLoadExecutor-3722-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> 968122 INFO  (coreLoadExecutor-3722-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 968122 INFO  (coreLoadExecutor-3722-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 968123 INFO  (coreLoadExecutor-3722-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=13, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=44.236328125, 
floorSegmentMB=0.8642578125, forceMergeDeletesPctAllowed=3.9089126134651977, 
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.3245942880460002
   [junit4]   2> 968125 INFO  (coreLoadExecutor-3722-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@a1608b[collection1] main]
   [junit4]   2> 968125 INFO  (coreLoadExecutor-3722-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/tempDir-002/collection1/conf
   [junit4]   2> 968126 INFO  (coreLoadExecutor-3722-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 968127 INFO  (searcherExecutor-3723-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@a1608b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1)))}
   [junit4]   2> 968128 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 968128 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 968129 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] 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> 968130 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[1]} 0 0
   [junit4]   2> 968130 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[2]} 0 0
   [junit4]   2> 968131 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[3]} 0 0
   [junit4]   2> 968131 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[4]} 0 0
   [junit4]   2> 968132 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 968132 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@11db99d commitCommandVersion:0
   [junit4]   2> 968838 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2bce96[collection1] main]
   [junit4]   2> 968839 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 968840 INFO  (searcherExecutor-3723-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2bce96[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.0.0):C4)))}
   [junit4]   2> 968841 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 709
   [junit4]   2> 968842 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_mvt_dvt_st_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 968843 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_mvt_dvt_sf_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 968844 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:3&fl=test_mvt_dvf_st_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 968844 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:4&fl=test_mvt_dvu_st_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 968844 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 968845 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] 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> 968845 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 968845 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=21641447
   [junit4]   2> 968845 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.node, tag=null
   [junit4]   2> 968846 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jvm, tag=null
   [junit4]   2> 968847 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jetty, tag=null
   [junit4]   2> 968848 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 968848 INFO  (coreCloseExecutor-3727-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@104c207
   [junit4]   2> 968849 INFO  (coreCloseExecutor-3727-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=17089031
   [junit4]   2> 968852 INFO  
(TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[92B7985AC914DA4C]) 
[    ] o.a.s.SolrTestCaseJ4 ###Ending testMultivaluedOrdering
   [junit4]   2> 968854 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued
   [junit4]   2> 968857 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 968864 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 968868 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 968870 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 968876 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with 
uniqueid field id
   [junit4]   2> 968877 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/tempDir-003/collection1/conf/managed-schema
   [junit4]   2> 968962 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, 
renamed the non-managed schema 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml
 to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 968990 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e0c71e
   [junit4]   2> 968994 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e0c71e
   [junit4]   2> 968994 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e0c71e
   [junit4]   2> 969001 INFO  (coreLoadExecutor-3734-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 969007 INFO  (coreLoadExecutor-3734-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
   [junit4]   2> 969012 INFO  (coreLoadExecutor-3734-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid 
field id
   [junit4]   2> 969012 INFO  (coreLoadExecutor-3734-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/tempDir-003/collection1,
 trusted=true
   [junit4]   2> 969012 INFO  (coreLoadExecutor-3734-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@e0c71e
   [junit4]   2> 969012 INFO  (coreLoadExecutor-3734-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 969012 INFO  (coreLoadExecutor-3734-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/tempDir-003/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/init-core-data-001/]
   [junit4]   2> 969015 WARN  (coreLoadExecutor-3734-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> 969046 INFO  (coreLoadExecutor-3734-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 969046 INFO  (coreLoadExecutor-3734-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 969048 INFO  (coreLoadExecutor-3734-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=13, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=44.236328125, 
floorSegmentMB=0.8642578125, forceMergeDeletesPctAllowed=3.9089126134651977, 
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.3245942880460002
   [junit4]   2> 969049 INFO  (coreLoadExecutor-3734-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1d88b79[collection1] main]
   [junit4]   2> 969049 INFO  (coreLoadExecutor-3734-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_92B7985AC914DA4C-001/tempDir-003/collection1/conf
   [junit4]   2> 969050 INFO  (coreLoadExecutor-3734-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 969051 INFO  (searcherExecutor-3735-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1d88b79[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.0.0):C4)))}
   [junit4]   2> 969051 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 969051 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 969052 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 969053 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 0
   [junit4]   2> 969053 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 969053 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1ec4682 commitCommandVersion:0
   [junit4]   2> 969489 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3c6717[collection1] main]
   [junit4]   2> 969490 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 969491 INFO  (searcherExecutor-3735-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3c6717[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):C1)))}
   [junit4]   2> 969492 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 438
   [junit4]   2> 969493 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_s_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 969494 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_s_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 969494 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 969495 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 969496 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=test_s_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 969496 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 969498 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 1
   [junit4]   2> 969498 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 969498 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1ec4682 commitCommandVersion:0
   [junit4]   2> 969900 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@596f4e[collection1] main]
   [junit4]   2> 969900 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 969902 INFO  (searcherExecutor-3735-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@596f4e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):C1)
 Uninverting(_3(7.0.0):C1)))}
   [junit4]   2> 969902 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 404
   [junit4]   2> 969903 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_is_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 969905 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_is_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 969906 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 969908 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 969909 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:2&fl=test_is_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 969910 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 969916 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 0
   [junit4]   2> 969917 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 969917 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1ec4682 commitCommandVersion:0
   [junit4]   2> 970247 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@eda94f[collection1] main]
   [junit4]   2> 970248 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 970249 INFO  (searcherExecutor-3735-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@eda94f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):C1)
 Uninverting(_3(7.0.0):C1) Uninverting(_4(7.0.0):C1)))}
   [junit4]   2> 970249 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 332
   [junit4]   2> 970250 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 970251 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 970251 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 970251 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 970252 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 970252 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 970253 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[4]} 0 0
   [junit4]   2> 970253 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 970253 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1ec4682 commitCommandVersion:0
   [junit4]   2> 970567 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6a662a[collection1] main]
   [junit4]   2> 970568 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 970569 INFO  (searcherExecutor-3735-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6a662a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):C1)
 Uninverting(_3(7.0.0):C1) Uninverting(_4(7.0.0):C1) 
Uninverting(_5(7.0.0):C1)))}
   [junit4]   2> 970569 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 315
   [junit4]   2> 970569 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 970570 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 970570 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 970571 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 970571 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 970572 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 970573 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 0
   [junit4]   2> 970573 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 970573 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1ec4682 commitCommandVersion:0
   [junit4]   2> 970815 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@17f1572[collection1] main]
   [junit4]   2> 970816 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 970817 INFO  (searcherExecutor-3735-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@17f1572[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):C1)
 Uninverting(_3(7.0.0):C1) Uninverting(_4(7.0.0):C1) Uninverting(_5(7.0.0):C1) 
Uninverting(_6(7.0.0):C1)))}
   [junit4]   2> 970817 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 244
   [junit4]   2> 970818 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_f_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 970818 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_f_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 970818 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 970819 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 970820 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:5&fl=test_f_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 970820 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 970822 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[6]} 0 0
   [junit4]   2> 970822 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 970822 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1ec4682 commitCommandVersion:0
   [junit4]   2> 971109 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@dd20c9[collection1] main]
   [junit4]   2> 971109 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 971110 INFO  (searcherExecutor-3735-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@dd20c9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):C1)
 Uninverting(_3(7.0.0):C1) Uninverting(_4(7.0.0):C1) Uninverting(_5(7.0.0):C1) 
Uninverting(_6(7.0.0):C1) Uninverting(_7(7.0.0):C1)))}
   [junit4]   2> 971111 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 288
   [junit4]   2> 971111 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 971112 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 971113 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 971114 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 971114 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 971115 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 971117 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[7]} 0 0
   [junit4]   2> 971117 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 971117 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1ec4682 commitCommandVersion:0
   [junit4]   2> 971373 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1651311[collection1] main]
   [junit4]   2> 971374 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 971375 INFO  (searcherExecutor-3735-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1651311[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):C1)
 Uninverting(_3(7.0.0):C1) Uninverting(_4(7.0.0):C1) Uninverting(_5(7.0.0):C1) 
Uninverting(_6(7.0.0):C1) Uninverting(_7(7.0.0):C1) Uninverting(_8(7.0.0):C1)))}
   [junit4]   2> 971375 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 258
   [junit4]   2> 971376 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_ss_dv&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 971377 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_ss_dv,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 971378 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 971380 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 971381 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:7&fl=test_ss_dv,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 971382 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 971385 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[8]} 0 0
   [junit4]   2> 971386 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 971386 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1ec4682 commitCommandVersion:0
   [junit4]   2> 971669 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1577b2[collection1] main]
   [junit4]   2> 971670 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 971671 INFO  (searcherExecutor-3735-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1577b2[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):C1)
 Uninverting(_3(7.0.0):C1) Uninverting(_4(7.0.0):C1) Uninverting(_5(7.0.0):C1) 
Uninverting(_6(7.0.0):C1) Uninverting(_7(7.0.0):C1) Uninverting(_8(7.0.0):C1) 
Uninverting(_9(7.0.0):C1)))}
   [junit4]   2> 971671 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 285
   [junit4]   2> 971671 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_s_dvo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 971672 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_s_dvo2,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 971672 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 971673 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 971673 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:8&fl=test_s_dvo2,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 971673 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
   [junit4]   2> 971675 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[9]} 0 0
   [junit4]   2> 971675 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 971675 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1ec4682 commitCommandVersion:0
   [junit4]   2> 971929 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@f3e9c0[collection1] main]
   [junit4]   2> 971929 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 971930 INFO  (searcherExecutor-3735-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@f3e9c0[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):C1)
 Uninverting(_3(7.0.0):C1) Uninverting(_4(7.0.0):C1) Uninverting(_5(7.0.0):C1) 
Uninverting(_6(7.0.0):C1) Uninverting(_7(7.0.0):C1) Uninverting(_8(7.0.0):C1) 
Uninverting(_9(7.0.0):C1) Uninverting(_a(7.0.0):C1)))}
   [junit4]   2> 971930 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 255
   [junit4]   2> 971931 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 971932 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 971933 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 971934 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 971935 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 971936 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=9 status=0 QTime=0
   [junit4]   2> 971938 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 971938 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 971938 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[10]} 0 0
   [junit4]   2> 971939 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 971939 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1ec4682 commitCommandVersion:0
   [junit4]   2> 972201 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@15d294a[collection1] main]
   [junit4]   2> 972202 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 972203 INFO  (searcherExecutor-3735-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@15d294a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(7.0.0):C1)))}
   [junit4]   2> 972208 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 268
   [junit4]   2> 972208 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 972209 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 972210 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 972210 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 972210 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 972211 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 972213 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[11]} 0 0
   [junit4]   2> 972213 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 972213 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1ec4682 commitCommandVersion:0
   [junit4]   2> 972495 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1003f05[collection1] main]
   [junit4]   2> 972495 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 972496 INFO  (searcherExecutor-3735-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1003f05[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(7.0.0):C1)
 Uninverting(_c(7.0.0):C1)))}
   [junit4]   2> 972496 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 282
   [junit4]   2> 972497 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 972498 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 972500 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 972501 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 972502 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 972503 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 972504 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[12]} 0 0
   [junit4]   2> 972505 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 972505 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1ec4682 commitCommandVersion:0
   [junit4]   2> 972758 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1eb85a3[collection1] main]
   [junit4]   2> 972759 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 972760 INFO  (searcherExecutor-3735-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1eb85a3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(7.0.0):C1)
 Uninverting(_c(7.0.0):C1) Uninverting(_d(7.0.0):C1)))}
   [junit4]   2> 972760 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 255
   [junit4]   2> 972761 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=test_d_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 972761 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=test_d_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 972762 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 972762 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 972762 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:12&fl=test_d_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 972763 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 972764 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[13]} 0 0
   [junit4]   2> 972764 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 972764 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1ec4682 commitCommandVersion:0
   [junit4]   2> 973074 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@174438f[collection1] main]
   [junit4]   2> 973075 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 973076 INFO  (searcherExecutor-3735-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@174438f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(7.0.0):C1)
 Uninverting(_c(7.0.0):C1) Uninverting(_d(7.0.0):C1) 
Uninverting(_e(7.0.0):C1)))}
   [junit4]   2> 973076 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 311
   [junit4]   2> 973077 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:13&fl=test_ls_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 973077 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:13&fl=test_ls_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 973078 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 973079 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 973080 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:13&fl=test_ls_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 973084 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 973085 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[14]} 0 0
   [junit4]   2> 973086 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 973086 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1ec4682 commitCommandVersion:0
   [junit4]   2> 973328 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1f5320f[collection1] main]
   [junit4]   2> 973328 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 973329 INFO  (searcherExecutor-3735-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1f5320f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(7.0.0):C1)
 Uninverting(_c(7.0.0):C1) Uninverting(_d(7.0.0):C1) Uninverting(_e(7.0.0):C1) 
Uninverting(_f(7.0.0):C1)))}
   [junit4]   2> 973329 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 243
   [junit4]   2> 973330 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_f_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 973330 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_f_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 973331 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 973331 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 973331 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&fl=test_f_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 973332 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 973333 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[15]} 0 0
   [junit4]   2> 973333 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 973333 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1ec4682 commitCommandVersion:0
   [junit4]   2> 973594 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@e34584[collection1] main]
   [junit4]   2> 973594 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 973595 INFO  (searcherExecutor-3735-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@e34584[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(7.0.0):C1)
 Uninverting(_c(7.0.0):C1) Uninverting(_d(7.0.0):C1) Uninverting(_e(7.0.0):C1) 
Uninverting(_f(7.0.0):C1) Uninverting(_g(7.0.0):C1)))}
   [junit4]   2> 973595 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 262
   [junit4]   2> 973596 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:15&fl=test_dt_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 973596 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:15&fl=test_dt_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 973597 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 973597 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] 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> 973598 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:15&fl=test_dt_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 973598 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 973599 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[16]} 0 0
   [junit4]   2> 973599 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 973599 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1ec4682 commitCommandVersion:0
   [junit4]   2> 973871 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@16d37bd[collection1] main]
   [junit4]   2> 973871 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 973873 INFO  (searcherExecutor-3735-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@16d37bd[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(7.0.0):C1)
 Uninverting(_c(7.0.0):C1) Uninverting(_d(7.0.0):C1) Uninverting(_e(7.0.0):C1) 
Uninverting(_f(7.0.0):C1) Uninverting(_g(7.0.0):C1) Uninverting(_h(7.0.0):C1)))}
   [junit4]   2> 973873 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 273
   [junit4]   2> 973873 INFO  
(TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null pa

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

 mergeFactor=15, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4594611690177426]
   [junit4]   2> 1052447 WARN  (coreLoadExecutor-3782-thread-1) [    ] 
o.a.s.c.RequestHandlers no default request handler is registered (either 
'/select' or 'standard')
   [junit4]   2> 1052447 INFO  (coreLoadExecutor-3782-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1052447 INFO  (coreLoadExecutor-3782-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1052448 INFO  (coreLoadExecutor-3782-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=40, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4060457611437469]
   [junit4]   2> 1052448 INFO  (coreLoadExecutor-3782-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@51deac[collection1] main]
   [junit4]   2> 1052449 WARN  (coreLoadExecutor-3782-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 1052449 INFO  (coreLoadExecutor-3782-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1052451 INFO  (searcherExecutor-3783-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@51deac[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1052451 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[92B7985AC914DA4C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1052453 INFO  
(TEST-UpdateRequestProcessorFactoryTest.testRequestTimeUrp-seed#[92B7985AC914DA4C])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testRequestTimeUrp
   [junit4]   2> 1052453 WARN  
(TEST-UpdateRequestProcessorFactoryTest.testRequestTimeUrp-seed#[92B7985AC914DA4C])
 [    ] o.a.s.c.PluginBag runtime library loading is not enabled, start Solr 
with -Denable.runtime.lib=true
   [junit4]   2> 1052453 INFO  
(TEST-UpdateRequestProcessorFactoryTest.testRequestTimeUrp-seed#[92B7985AC914DA4C])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testRequestTimeUrp
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=UpdateRequestProcessorFactoryTest -Dtests.method=testRequestTimeUrp 
-Dtests.seed=92B7985AC914DA4C -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=fr-MQ -Dtests.timezone=Africa/Kinshasa -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.00s J1 | 
UpdateRequestProcessorFactoryTest.testRequestTimeUrp <<<
   [junit4]    > Throwable #1: org.apache.solr.common.SolrException: No such 
processor Template
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([92B7985AC914DA4C:3705D8926F5A13B8]:0)
   [junit4]    >        at 
org.apache.solr.update.processor.UpdateRequestProcessorChain.getReqProcessors(UpdateRequestProcessorChain.java:286)
   [junit4]    >        at 
org.apache.solr.update.processor.UpdateRequestProcessorChain.constructChain(UpdateRequestProcessorChain.java:235)
   [junit4]    >        at 
org.apache.solr.core.SolrCore.getUpdateProcessorChain(SolrCore.java:1446)
   [junit4]    >        at 
org.apache.solr.update.processor.UpdateRequestProcessorFactoryTest.testRequestTimeUrp(UpdateRequestProcessorFactoryTest.java:53)
   [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> 1052456 INFO  
(TEST-UpdateRequestProcessorFactoryTest.testUpdateDistribChainSkipping-seed#[92B7985AC914DA4C])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testUpdateDistribChainSkipping
   [junit4]   2> 1052458 INFO  
(TEST-UpdateRequestProcessorFactoryTest.testUpdateDistribChainSkipping-seed#[92B7985AC914DA4C])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testUpdateDistribChainSkipping
   [junit4]   2> 1052459 INFO  
(TEST-UpdateRequestProcessorFactoryTest.testConfiguration-seed#[92B7985AC914DA4C])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testConfiguration
   [junit4]   2> 1052460 INFO  
(TEST-UpdateRequestProcessorFactoryTest.testConfiguration-seed#[92B7985AC914DA4C])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testConfiguration
   [junit4]   2> 1052460 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[92B7985AC914DA4C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1052460 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[92B7985AC914DA4C]-worker) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=7584880
   [junit4]   2> 1052460 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[92B7985AC914DA4C]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1052461 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[92B7985AC914DA4C]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 1052462 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[92B7985AC914DA4C]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 1052462 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[92B7985AC914DA4C]-worker) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1052462 INFO  (coreCloseExecutor-3787-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@10057e0
   [junit4]   2> 1052463 INFO  (coreCloseExecutor-3787-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=16799712
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.UpdateRequestProcessorFactoryTest_92B7985AC914DA4C-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {}, 
docValues:{}, maxPointsInLeafNode=847, maxMBSortInHeap=6.477662256924798, 
sim=RandomSimilarity(queryNorm=false): {}, locale=fr-MQ, 
timezone=Africa/Kinshasa
   [junit4]   2> NOTE: Linux 4.4.0-75-generic i386/Oracle Corporation 9-ea 
(32-bit)/cpus=12,threads=1,free=342281264,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [SortByFunctionTest, 
BlockJoinFacetRandomTest, TestHashPartitioner, ConnectionManagerTest, 
TestRTGBase, DistributedTermsComponentTest, InfixSuggestersTest, 
CollectionStateFormat2Test, CleanupOldIndexTest, DeleteInactiveReplicaTest, 
JvmMetricsTest, TestSizeLimitedDistributedMap, CoreAdminRequestStatusTest, 
TestStressVersions, ShardRoutingCustomTest, TestSurroundQueryParser, 
TestUninvertingReader, TestCustomStream, LeaderFailoverAfterPartitionTest, 
IndexBasedSpellCheckerTest, AtomicUpdateProcessorFactoryTest, 
LoggingHandlerTest, WrapperMergePolicyFactoryTest, TestBM25SimilarityFactory, 
TestNonDefinedSimilarityFactory, DistributedQueryComponentOptimizationTest, 
ChaosMonkeySafeLeaderTest, HdfsBasicDistributedZk2Test, TestJsonFacets, 
TestDownShardTolerantSearch, SolrIndexMetricsTest, TestFiltering, 
TestLeaderElectionWithEmptyReplica, TestFieldTypeCollectionResource, 
UpdateLogTest, ExitableDirectoryReaderTest, SharedFSAutoReplicaFailoverTest, 
TestSQLHandlerNonCloud, HdfsChaosMonkeySafeLeaderTest, AnalyticsQueryTest, 
TestDistributedGrouping, TestBulkSchemaConcurrent, 
ClassificationUpdateProcessorIntegrationTest, ConvertedLegacyTest, 
TestDelegationWithHadoopAuth, TestStressLiveNodes, DistributedMLTComponentTest, 
DirectSolrSpellCheckerTest, OnlyLeaderIndexesTest, ScriptEngineTest, 
HdfsSyncSliceTest, SchemaVersionSpecificBehaviorTest, AssignTest, 
DistanceFunctionTest, CollectionsAPISolrJTest, MetricUtilsTest, 
TestAuthenticationFramework, ForceLeaderTest, TestStressRecovery, 
TestCloudJSONFacetJoinDomain, ZkCLITest, TestPostingsSolrHighlighter, 
TestSSLRandomization, TestQueryUtils, PreAnalyzedUpdateProcessorTest, 
MergeStrategyTest, LeaderInitiatedRecoveryOnShardRestartTest, 
TestXIncludeConfig, TestBinaryResponseWriter, DocValuesTest, JsonLoaderTest, 
DOMUtilTest, TlogReplayBufferedWhileIndexingTest, TestSolrCoreSnapshots, 
SliceStateTest, TestCollectionAPIs, SimplePostToolTest, SoftAutoCommitTest, 
SolrCLIZkUtilsTest, TestClusterStateMutator, TestCoreContainer, 
HLLSerializationTest, TestHdfsUpdateLog, SolrCmdDistributorTest, 
TestCharFilters, BasicFunctionalityTest, DistributedQueueTest, 
DistributedFacetPivotLargeTest, TestClassNameShortening, SolrIndexSplitterTest, 
TestSerializedLuceneMatchVersion, TestSchemaResource, TestCloudRecovery, 
IndexSchemaRuntimeFieldTest, TestPKIAuthenticationPlugin, BlockCacheTest, 
TestTrackingShardHandlerFactory, TestEmbeddedSolrServerConstructors, 
SpatialFilterTest, OverseerCollectionConfigSetProcessorTest, 
TestConfigSetProperties, CdcrUpdateLogTest, TestPartialUpdateDeduplication, 
SegmentsInfoRequestHandlerTest, SolrGraphiteReporterTest, 
TestSweetSpotSimilarityFactory, TestSolrCloudWithSecureImpersonation, 
TestSubQueryTransformerCrossCore, TestScoreJoinQPScore, 
TestUnifiedSolrHighlighter, TestUniqueKeyFieldResource, TestSearcherReuse, 
SolrInfoBeanTest, TestStandardQParsers, TestLocalFSCloudBackupRestore, 
TestUseDocValuesAsStored, UpdateRequestProcessorFactoryTest]
   [junit4] Completed [363/709 (2!)] on J1 in 0.31s, 3 tests, 1 error <<< 
FAILURES!

[...truncated 37421 lines...]

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