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 # 869 - Still Unstable!
Date Sat, 03 Jun 2017 21:23:39 GMT
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Solaris/869/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC

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

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

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




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

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

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

[...truncated 43645 lines...]

Mime
View raw message