lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-6.x-Solaris (64bit/jdk1.8.0) - Build # 779 - Unstable!
Date Mon, 10 Apr 2017 09:26:52 GMT
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Solaris/779/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC

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

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

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




Build Log:
[...truncated 12987 lines...]
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4]   2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E0203159C4EE26FA-001/init-core-data-001
   [junit4]   2> 2733722 WARN  (SUITE-TestUseDocValuesAsStored-seed#[E0203159C4EE26FA]-worker) [    ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 2733723 INFO  (SUITE-TestUseDocValuesAsStored-seed#[E0203159C4EE26FA]-worker) [    ] o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 2733725 INFO  (SUITE-TestUseDocValuesAsStored-seed#[E0203159C4EE26FA]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 2733729 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued
   [junit4]   2> 2733735 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 2733745 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 2733767 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2733770 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 2733785 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
   [junit4]   2> 2733786 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E0203159C4EE26FA-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 2733787 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E0203159C4EE26FA-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml to /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E0203159C4EE26FA-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 2733790 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 2733831 WARN  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 2733834 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46003118
   [junit4]   2> 2733834 WARN  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 2733839 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46003118
   [junit4]   2> 2733839 WARN  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 2733840 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46003118
   [junit4]   2> 2733857 INFO  (coreLoadExecutor-10132-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 2733874 INFO  (coreLoadExecutor-10132-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
   [junit4]   2> 2733882 INFO  (coreLoadExecutor-10132-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
   [junit4]   2> 2733882 INFO  (coreLoadExecutor-10132-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E0203159C4EE26FA-001/tempDir-001/collection1, trusted=true
   [junit4]   2> 2733882 WARN  (coreLoadExecutor-10132-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 2733883 INFO  (coreLoadExecutor-10132-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46003118
   [junit4]   2> 2733883 INFO  (coreLoadExecutor-10132-thread-1) [    x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2733883 INFO  (coreLoadExecutor-10132-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E0203159C4EE26FA-001/tempDir-001/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E0203159C4EE26FA-001/init-core-data-001/]
   [junit4]   2> 2733885 INFO  (coreLoadExecutor-10132-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=44, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=27.095703125, floorSegmentMB=1.0947265625, forceMergeDeletesPctAllowed=9.516561274462362, segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3640372558843723
   [junit4]   2> 2733890 WARN  (coreLoadExecutor-10132-thread-1) [    x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, registerPath=/solr,/v2, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 2734016 INFO  (coreLoadExecutor-10132-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2734017 INFO  (coreLoadExecutor-10132-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2734018 INFO  (coreLoadExecutor-10132-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1974274693, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 2734019 INFO  (coreLoadExecutor-10132-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7a3bd12f[collection1] main]
   [junit4]   2> 2734020 INFO  (coreLoadExecutor-10132-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E0203159C4EE26FA-001/tempDir-001/collection1/conf
   [junit4]   2> 2734020 INFO  (coreLoadExecutor-10132-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2734020 INFO  (searcherExecutor-10133-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7a3bd12f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2734022 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 2734023 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 2734023 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734025 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 1
   [junit4]   2> 2734028 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734029 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734050 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5e133463[collection1] main]
   [junit4]   2> 2734051 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734051 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5e133463[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1)))}
   [junit4]   2> 2734051 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 22
   [junit4]   2> 2734053 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:1&fl=test_ss_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734056 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:1&fl=test_ss_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734058 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734059 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734060 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:1&fl=test_ss_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734060 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734062 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 1
   [junit4]   2> 2734064 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734064 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734090 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@e95683f[collection1] main]
   [junit4]   2> 2734090 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734090 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@e95683f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1) Uninverting(_1(6.6.0):c1)))}
   [junit4]   2> 2734091 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 27
   [junit4]   2> 2734091 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734093 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734094 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734094 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734095 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734095 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 2734097 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 2734097 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734097 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734104 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5cd862ce[collection1] main]
   [junit4]   2> 2734105 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734105 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5cd862ce[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1) Uninverting(_1(6.6.0):c1) Uninverting(_2(6.6.0):c1)))}
   [junit4]   2> 2734105 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 8
   [junit4]   2> 2734106 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:3&fl=test_ds_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734107 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:3&fl=test_ds_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734107 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734108 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734109 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:3&fl=test_ds_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734110 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 2734111 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[4]} 0 0
   [junit4]   2> 2734111 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734112 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734121 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@46c8acff[collection1] main]
   [junit4]   2> 2734121 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734121 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@46c8acff[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1) Uninverting(_1(6.6.0):c1) Uninverting(_2(6.6.0):c1) Uninverting(_3(6.6.0):c1)))}
   [junit4]   2> 2734121 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 10
   [junit4]   2> 2734122 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734123 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734124 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734124 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734125 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734125 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 2734145 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[5]} 0 5
   [junit4]   2> 2734145 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734145 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734164 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2463ef6e[collection1] main]
   [junit4]   2> 2734165 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734165 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2463ef6e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1) Uninverting(_1(6.6.0):c1) Uninverting(_2(6.6.0):c1) Uninverting(_3(6.6.0):c1) Uninverting(_4(6.6.0):c1)))}
   [junit4]   2> 2734165 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 19
   [junit4]   2> 2734165 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734166 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734167 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734167 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734168 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734168 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 2734170 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[6]} 0 0
   [junit4]   2> 2734170 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734171 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734181 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7c5231ba[collection1] main]
   [junit4]   2> 2734182 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734182 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7c5231ba[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1) Uninverting(_1(6.6.0):c1) Uninverting(_2(6.6.0):c1) Uninverting(_3(6.6.0):c1) Uninverting(_4(6.6.0):c1) Uninverting(_5(6.6.0):c1)))}
   [junit4]   2> 2734182 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 11
   [junit4]   2> 2734183 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:6&fl=test_dt_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734184 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:6&fl=test_dt_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734185 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734186 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734186 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:6&fl=test_dt_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734187 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 2734188 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[7]} 0 0
   [junit4]   2> 2734188 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734189 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734200 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@407be167[collection1] main]
   [junit4]   2> 2734200 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734201 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@407be167[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1) Uninverting(_1(6.6.0):c1) Uninverting(_2(6.6.0):c1) Uninverting(_3(6.6.0):c1) Uninverting(_4(6.6.0):c1) Uninverting(_5(6.6.0):c1) Uninverting(_6(6.6.0):c1)))}
   [junit4]   2> 2734201 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 12
   [junit4]   2> 2734202 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734203 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734204 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734205 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734206 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734207 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 2734209 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[8]} 0 0
   [junit4]   2> 2734209 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734209 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734220 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@209fe84[collection1] main]
   [junit4]   2> 2734221 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734222 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@209fe84[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1) Uninverting(_1(6.6.0):c1) Uninverting(_2(6.6.0):c1) Uninverting(_3(6.6.0):c1) Uninverting(_4(6.6.0):c1) Uninverting(_5(6.6.0):c1) Uninverting(_6(6.6.0):c1) Uninverting(_7(6.6.0):c1)))}
   [junit4]   2> 2734222 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 12
   [junit4]   2> 2734223 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:8&fl=test_ss_dvo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734224 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:8&fl=test_ss_dvo2,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734224 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734225 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734226 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:8&fl=test_ss_dvo2,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734227 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
   [junit4]   2> 2734228 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[9]} 0 0
   [junit4]   2> 2734229 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734229 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734241 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@58452513[collection1] main]
   [junit4]   2> 2734241 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734244 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@58452513[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c1) Uninverting(_1(6.6.0):c1) Uninverting(_2(6.6.0):c1) Uninverting(_3(6.6.0):c1) Uninverting(_4(6.6.0):c1) Uninverting(_5(6.6.0):c1) Uninverting(_6(6.6.0):c1) Uninverting(_7(6.6.0):c1) Uninverting(_8(6.6.0):c1)))}
   [junit4]   2> 2734244 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 15
   [junit4]   2> 2734245 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734247 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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=1
   [junit4]   2> 2734248 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734249 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734249 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734250 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=9 status=0 QTime=0
   [junit4]   2> 2734251 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 2734252 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734253 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[10]} 0 0
   [junit4]   2> 2734253 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734253 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734267 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@19bcaf1f[collection1] main]
   [junit4]   2> 2734268 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734268 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@19bcaf1f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.6.0):c1)))}
   [junit4]   2> 2734271 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 17
   [junit4]   2> 2734272 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734273 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734273 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734274 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734274 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734275 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734276 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[11]} 0 0
   [junit4]   2> 2734276 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734277 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734285 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4c09f297[collection1] main]
   [junit4]   2> 2734286 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4c09f297[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.6.0):c1) Uninverting(_a(6.6.0):c1)))}
   [junit4]   2> 2734286 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734286 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 9
   [junit4]   2> 2734287 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:11&fl=test_i_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734287 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:11&fl=test_i_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734288 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734288 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734288 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:11&fl=test_i_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734289 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 2734289 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[12]} 0 0
   [junit4]   2> 2734290 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734290 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734299 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@64d18f64[collection1] main]
   [junit4]   2> 2734300 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@64d18f64[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.6.0):c1) Uninverting(_a(6.6.0):c1) Uninverting(_b(6.6.0):c1)))}
   [junit4]   2> 2734300 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734300 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 10
   [junit4]   2> 2734300 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734301 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734301 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734302 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734302 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734303 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 2734304 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[13]} 0 1
   [junit4]   2> 2734305 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734305 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734314 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@ad06044[collection1] main]
   [junit4]   2> 2734319 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@ad06044[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.6.0):c1) Uninverting(_a(6.6.0):c1) Uninverting(_b(6.6.0):c1) Uninverting(_c(6.6.0):c1)))}
   [junit4]   2> 2734319 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734319 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 14
   [junit4]   2> 2734320 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734322 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734323 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734324 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734326 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734328 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 2734330 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[14]} 0 0
   [junit4]   2> 2734330 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734330 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734339 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@12adb27d[collection1] main]
   [junit4]   2> 2734340 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@12adb27d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.6.0):c1) Uninverting(_a(6.6.0):c1) Uninverting(_b(6.6.0):c1) Uninverting(_c(6.6.0):c1) Uninverting(_d(6.6.0):c1)))}
   [junit4]   2> 2734340 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734340 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 9
   [junit4]   2> 2734341 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734341 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734342 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734342 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734343 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734343 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 2734345 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[15]} 0 1
   [junit4]   2> 2734346 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734346 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734356 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1813c60a[collection1] main]
   [junit4]   2> 2734357 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1813c60a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.6.0):c1) Uninverting(_a(6.6.0):c1) Uninverting(_b(6.6.0):c1) Uninverting(_c(6.6.0):c1) Uninverting(_d(6.6.0):c1) Uninverting(_e(6.6.0):c1)))}
   [junit4]   2> 2734357 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734357 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 10
   [junit4]   2> 2734357 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:15&fl=test_dts_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734358 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:15&fl=test_dts_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734359 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734360 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734361 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:15&fl=test_dts_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734362 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 2734365 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[16]} 0 1
   [junit4]   2> 2734366 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734366 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734376 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4d85a549[collection1] main]
   [junit4]   2> 2734377 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4d85a549[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.6.0):c1) Uninverting(_a(6.6.0):c1) Uninverting(_b(6.6.0):c1) Uninverting(_c(6.6.0):c1) Uninverting(_d(6.6.0):c1) Uninverting(_e(6.6.0):c1) Uninverting(_f(6.6.0):c1)))}
   [junit4]   2> 2734377 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734377 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 11
   [junit4]   2> 2734378 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:16&fl=test_ss_dv&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734379 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:16&fl=test_ss_dv,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734380 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:16&fl=*_dv&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734381 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:16&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734382 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:16&fl=test_ss_dv,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734384 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
   [junit4]   2> 2734386 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[17]} 0 0
   [junit4]   2> 2734387 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734387 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734401 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@74b2c353[collection1] main]
   [junit4]   2> 2734402 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@74b2c353[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.6.0):c1) Uninverting(_a(6.6.0):c1) Uninverting(_b(6.6.0):c1) Uninverting(_c(6.6.0):c1) Uninverting(_d(6.6.0):c1) Uninverting(_e(6.6.0):c1) Uninverting(_f(6.6.0):c1) Uninverting(_g(6.6.0):c1)))}
   [junit4]   2> 2734402 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734402 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 15
   [junit4]   2> 2734404 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:17&fl=test_ss_dvo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734405 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:17&fl=test_ss_dvo2,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734405 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:17&fl=*vo2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734406 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:17&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734406 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:17&fl=test_ss_dvo2,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734407 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
   [junit4]   2> 2734409 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[18]} 0 0
   [junit4]   2> 2734409 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734409 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734419 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@16480c20[collection1] main]
   [junit4]   2> 2734420 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@16480c20[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.6.0):c1) Uninverting(_a(6.6.0):c1) Uninverting(_b(6.6.0):c1) Uninverting(_c(6.6.0):c1) Uninverting(_d(6.6.0):c1) Uninverting(_e(6.6.0):c1) Uninverting(_f(6.6.0):c1) Uninverting(_g(6.6.0):c1) Uninverting(_h(6.6.0):c1)))}
   [junit4]   2> 2734420 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734420 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 11
   [junit4]   2> 2734421 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:18&fl=enum_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734422 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:18&fl=enum_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734422 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:18&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734422 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:18&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734423 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:18&fl=enum_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734423 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=9 status=0 QTime=0
   [junit4]   2> 2734424 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 2734424 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] 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> 2734426 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[19]} 0 0
   [junit4]   2> 2734426 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734426 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734440 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@367209c4[collection1] main]
   [junit4]   2> 2734440 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@367209c4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(6.6.0):c1)))}
   [junit4]   2> 2734443 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734443 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 17
   [junit4]   2> 2734600 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:19&fl=test_ss_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734601 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:19&fl=test_ss_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734602 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:19&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734604 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:19&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734605 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:19&fl=test_ss_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734607 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734609 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[20]} 0 0
   [junit4]   2> 2734609 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734609 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734662 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@707244fa[collection1] main]
   [junit4]   2> 2734663 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734663 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@707244fa[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(6.6.0):c1) Uninverting(_j(6.6.0):c1)))}
   [junit4]   2> 2734663 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 53
   [junit4]   2> 2734664 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:20&fl=test_is_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734665 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:20&fl=test_is_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734666 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:20&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734666 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:20&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734667 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:20&fl=test_is_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734668 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 2734669 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[21]} 0 0
   [junit4]   2> 2734670 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734670 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734697 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@55d238e8[collection1] main]
   [junit4]   2> 2734698 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734698 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@55d238e8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(6.6.0):c1) Uninverting(_j(6.6.0):c1) Uninverting(_k(6.6.0):c1)))}
   [junit4]   2> 2734698 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 28
   [junit4]   2> 2734699 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:21&fl=test_d_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734700 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:21&fl=test_d_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734700 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:21&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734701 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:21&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734701 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:21&fl=test_d_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734702 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 2734704 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[22]} 0 0
   [junit4]   2> 2734704 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734704 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734720 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7bb52d3[collection1] main]
   [junit4]   2> 2734721 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734721 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7bb52d3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(6.6.0):c1) Uninverting(_j(6.6.0):c1) Uninverting(_k(6.6.0):c1) Uninverting(_l(6.6.0):c1)))}
   [junit4]   2> 2734721 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 17
   [junit4]   2> 2734721 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:22&fl=test_ls_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734728 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:22&fl=test_ls_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734729 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:22&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734730 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:22&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734730 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:22&fl=test_ls_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734731 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 2734732 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[23]} 0 0
   [junit4]   2> 2734732 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734733 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734777 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@8aeb9fb[collection1] main]
   [junit4]   2> 2734778 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734778 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@8aeb9fb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(6.6.0):c1) Uninverting(_j(6.6.0):c1) Uninverting(_k(6.6.0):c1) Uninverting(_l(6.6.0):c1) Uninverting(_m(6.6.0):c1)))}
   [junit4]   2> 2734778 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 45
   [junit4]   2> 2734779 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:23&fl=test_fs_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734781 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:23&fl=test_fs_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734783 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:23&fl=*dvo&wt=xml} hits=1 status=0 QTime=1
   [junit4]   2> 2734785 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:23&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734786 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:23&fl=test_fs_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734788 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 2734792 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[24]} 0 2
   [junit4]   2> 2734793 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734793 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a0a6826
   [junit4]   2> 2734864 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5e125909[collection1] main]
   [junit4]   2> 2734865 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2734865 INFO  (searcherExecutor-10133-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5e125909[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(6.6.0):c1) Uninverting(_j(6.6.0):c1) Uninverting(_k(6.6.0):c1) Uninverting(_l(6.6.0):c1) Uninverting(_m(6.6.0):c1) Uninverting(_n(6.6.0):c1)))}
   [junit4]   2> 2734865 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 72
   [junit4]   2> 2734866 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:24&fl=test_dts_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734867 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:24&fl=test_dts_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734867 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:24&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734868 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:24&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734869 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:24&fl=test_dts_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2734870 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 2734871 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[25]} 0 0
   [junit4]   2> 2734872 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2734872 INFO  (TEST-TestUseD

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

irectUpdateHandler2 end_commit_flush
   [junit4]   2> 2738639 INFO  (searcherExecutor-10193-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@77b6280a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2n(6.6.0):c1)))}
   [junit4]   2> 2738640 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E0203159C4EE26FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 18
   [junit4]   2> 2738641 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 2738641 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E0203159C4EE26FA]) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 2738641 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=943569510
   [junit4]   2> 2738641 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E0203159C4EE26FA]) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 2738642 INFO  (coreCloseExecutor-10197-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@54f9a389
   [junit4]   2> 2738643 INFO  (coreCloseExecutor-10197-thread-1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 2738648 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E0203159C4EE26FA]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 2738650 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[E0203159C4EE26FA]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testOnEmptyIndex
   [junit4]   2> 2738651 INFO  (SUITE-TestUseDocValuesAsStored-seed#[E0203159C4EE26FA]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_E0203159C4EE26FA-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): {}, docValues:{}, maxPointsInLeafNode=1530, maxMBSortInHeap=5.990139709566396, sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=es-PY, timezone=JST
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_121 (64-bit)/cpus=3,threads=1,free=269052640,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: [CursorPagingTest, TestUninvertingReader, TestMaxScoreQueryParser, TestPerFieldSimilarity, ClassificationUpdateProcessorTest, BigEndianAscendingWordDeserializerTest, TestBinaryResponseWriter, TestPivotHelperCode, HighlighterTest, SolrPluginUtilsTest, UnloadDistributedZkTest, DocumentBuilderTest, TestReloadDeadlock, XmlUpdateRequestHandlerTest, JsonValidatorTest, ResponseLogComponentTest, TestStressReorder, TestSubQueryTransformerCrossCore, AnalysisAfterCoreReloadTest, VMParamsZkACLAndCredentialsProvidersTest, TestRemoteStreaming, TestLRUCache, SimpleFacetsTest, TestHashQParserPlugin, DocumentAnalysisRequestHandlerTest, TestManagedSynonymFilterFactory, FastVectorHighlighterTest, TestSolrCloudSnapshots, TestTolerantUpdateProcessorCloud, TestOverriddenPrefixQueryForCustomFieldType, DocValuesTest, CdcrReplicationDistributedZkTest, HttpSolrCallGetCoreTest, TestSweetSpotSimilarityFactory, HdfsWriteToMultipleCollectionsTest, TestMiniSolrCloudClusterSSL, OverseerModifyCollectionTest, ShufflingReplicaListTransformerTest, TestNumericTerms32, BitVectorTest, DistributedDebugComponentTest, QueryElevationComponentTest, TestRandomFlRTGCloud, TestExtendedDismaxParser, RequestLoggingTest, MoreLikeThisHandlerTest, TestRequestForwarding, TestFieldTypeResource, TestDownShardTolerantSearch, SolrCoreTest, TestRebalanceLeaders, SchemaVersionSpecificBehaviorTest, AssignTest, TestBlendedInfixSuggestions, TestConfigSetsAPI, DistributedSuggestComponentTest, SaslZkACLProviderTest, TestConfigsApi, TestComplexPhraseLeadingWildcard, BooleanFieldTest, ReplicationFactorTest, LargeFieldTest, BlockJoinFacetDistribTest, SmileWriterTest, FullHLLTest, LeaderInitiatedRecoveryOnCommitTest, BasicAuthIntegrationTest, TestPostingsSolrHighlighter, IndexBasedSpellCheckerTest, TestMacroExpander, TestSolrDynamicMBean, TestShortCircuitedRequests, PeerSyncTest, TestTolerantSearch, DistribDocExpirationUpdateProcessorTest, SolrJmxReporterTest, TestCorePropertiesReload, SolrGangliaReporterTest, DateRangeFieldTest, DirectSolrSpellCheckerTest, TestNRTOpen, SimplePostToolTest, CloudExitableDirectoryReaderTest, TestLocalFSCloudBackupRestore, SolrCloudExampleTest, TimeZoneUtilsTest, BaseCdcrDistributedZkTest, InfixSuggestersTest, DistributedFacetPivotSmallTest, TestReplicationHandlerBackup, HdfsRecoveryZkTest, TestStressUserVersions, TestManagedResource, DirectSolrConnectionTest, AnalyticsQueryTest, CachingDirectoryFactoryTest, UniqFieldsUpdateProcessorFactoryTest, TestDistribIDF, DocExpirationUpdateProcessorFactoryTest, TestSchemaResource, TestLazyCores, TestInPlaceUpdatesDistrib, DateFieldTest, TestMinMaxOnMultiValuedField, TestJmxIntegration, ActionThrottleTest, PrimitiveFieldTypeTest, TestConfigOverlay, ZkControllerTest, CollectionsAPIAsyncDistributedZkTest, ZkStateWriterTest, ConnectionManagerTest, TestSolrJ, TestExactStatsCache, TestTestInjection, TestLeaderElectionZkExpiry, SystemInfoHandlerTest, MigrateRouteKeyTest, TestExportWriter, DistributedQueueTest, TestSSLRandomization, TestElisionMultitermQuery, TestScoreJoinQPNoScore, PeerSyncReplicationTest, ClassificationUpdateProcessorFactoryTest, RemoteQueryErrorTest, SOLR749Test, TestPerFieldSimilarityClassic, CurrencyFieldXmlFileTest, RequiredFieldsTest, SolrMetricReporterTest, TestSolrConfigHandlerConcurrent, DisMaxRequestHandlerTest, TestReplicationHandler, ParsingFieldUpdateProcessorsTest, ClusterStateUpdateTest, TestStressVersions, ExitableDirectoryReaderTest, TestCollationFieldDocValues, TestFieldResource, NumericFieldsTest, TestFreeTextSuggestions, TestInfoStreamLogging, TestSchemalessBufferedUpdates, HdfsTlogReplayBufferedWhileIndexingTest, TestRTGBase, ChaosMonkeyNothingIsSafeTest, TestAnalyzedSuggestions, SoftAutoCommitTest, DistributedMLTComponentTest, CollectionStateFormat2Test, DataDrivenBlockJoinTest, TestDocumentBuilder, TestBM25SimilarityFactory, SegmentsInfoRequestHandlerTest, HardAutoCommitTest, TestSort, OverseerStatusTest, TestRestManager, TestPartialUpdateDeduplication, TestUniqueKeyFieldResource, TestDistributedStatsComponentCardinality, SuggestComponentContextFilterQueryTest, SolrCoreMetricManagerTest, NodeMutatorTest, TestSolrFieldCacheMBean, ConcurrentDeleteAndCreateCollectionTest, TestRestoreCore, OutputWriterTest, DocValuesNotIndexedTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, OpenCloseCoreStressTest, OverseerTest, BasicZkTest, LeaderElectionTest, ZkCLITest, TestDistributedSearch, TestReversedWildcardFilterFactory, FileBasedSpellCheckerTest, PathHierarchyTokenizerFactoryTest, FieldAnalysisRequestHandlerTest, TestCollationField, JSONWriterTest, MBeansHandlerTest, TestPHPSerializedResponseWriter, CSVRequestHandlerTest, TestComponentsName, TestLFUCache, TestMergePolicyConfig, TestSolrDeletionPolicy2, MultiTermTest, SampleTest, TestDocSet, TestBinaryField, TestConfig, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, TestPhraseSuggestions, TestCharFilters, SynonymTokenizerTest, TestXIncludeConfig, TestDFRSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestIBSimilarityFactory, TestFastWriter, TestJmxMonitoredMap, ResourceLoaderTest, OpenExchangeRatesOrgProviderTest, TestFastLRUCache, PrimUtilsTest, ClusterStateTest, ZkNodePropsTest, FileUtilsTest, TestCursorMarkWithoutUniqueKey, TestPathTrie, TestEmbeddedSolrServerConstructors, TestEmbeddedSolrServerSchemaAPI, TestJettySolrRunner, ConnectionReuseTest, CdcrRequestHandlerTest, CollectionReloadTest, CollectionsAPISolrJTest, DeleteInactiveReplicaTest, DeleteLastCustomShardedReplicaTest, DeleteReplicaTest, DeleteStatusTest, DistributedVersionInfoTest, HttpPartitionTest, LeaderFailoverAfterPartitionTest, MoveReplicaTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerRolesTest, RestartWhileUpdatingTest, RollingRestartTest, SharedFSAutoReplicaFailoverTest, SharedFSAutoReplicaFailoverUtilsTest, SimpleCollectionCreateDeleteTest, TestAuthenticationFramework, TestCloudDeleteByQuery, TestCloudInspectUtil, TestCloudPseudoReturnFields, TestClusterProperties, TestConfigSetsAPIExclusivity, TestConfigSetsAPIZkFailure, TestCryptoKeys, TestDeleteCollectionOnDownNodes, TestDistribDocBasedVersion, TestExclusionRuleCollectionAccess, TestHdfsCloudBackupRestore, TestLeaderInitiatedRecoveryThread, TestLockTree, TestMiniSolrCloudCluster, TestSegmentSorting, TestStressInPlaceUpdates, HdfsSyncSliceTest, HdfsUnloadDistributedZkTest, TestClusterStateMutator, RuleEngineTest, RulesTest, QueryResultKeyTest, TestBackupRepositoryFactory, TestConfigSetProperties, TestConfigSets, TestCustomStream, TestImplicitCoreProperties, TestShardHandlerFactory, TestSolrXml, TestConfigReload, TestHdfsBackupRestoreCore, TestSQLHandlerNonCloud, TestSolrConfigHandlerCloud, DistributedFacetPivotLargeTest, DistributedFacetPivotWhiteBoxTest, DistributedQueryComponentOptimizationTest, SuggestComponentTest, TestExpandComponent, JavabinLoaderTest, HighlighterMaxOffsetTest, TestUnifiedSolrHighlighter, TestSlowCompositeReaderWrapper, WrapperMergePolicyFactoryTest, CheckHdfsIndexTest, TestClassNameShortening, TestDefaultSearchFieldResource, TestFieldCollectionResource, TestSerializedLuceneMatchVersion, TestSolrQueryParserResource, EnumFieldTest, TestBulkSchemaConcurrent, TestSchemaManager, TestUseDocValuesAsStored]
   [junit4] Completed [625/703 (1!)] on J0 in 4.95s, 6 tests, 1 error <<< FAILURES!

[...truncated 43618 lines...]


Mime
View raw message