You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/07/07 10:23:48 UTC
[JENKINS] Lucene-Solr-8.x-Linux (64bit/jdk-11.0.3) - Build # 835 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/835/
Java: 64bit/jdk-11.0.3 -XX:+UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults
Error Message:
mismatch: 'myid1'!='myid' @ response/docs/[0]/id
Stack Trace:
java.lang.RuntimeException: mismatch: 'myid1'!='myid' @ response/docs/[0]/id
at __randomizedtesting.SeedInfo.seed([5492CE219E1804D1:66B8C9BF66E62008]:0)
at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:1035)
at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:982)
at org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults(TestUseDocValuesAsStored.java:255)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
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:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:834)
FAILED: org.apache.solr.schema.TestUseDocValuesAsStored.testDuplicateMultiValued
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at __randomizedtesting.SeedInfo.seed([5492CE219E1804D1:BA4FDA0150A9F26D]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:947)
at org.apache.solr.schema.TestUseDocValuesAsStored.doTest(TestUseDocValuesAsStored.java:367)
at org.apache.solr.schema.TestUseDocValuesAsStored.testDuplicateMultiValued(TestUseDocValuesAsStored.java:164)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
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:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:834)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//arr[@name='test_ss_dvo']/str[.='X']
xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="17" start="0"><doc><str name="id">262</str><arr name="test_ss_dvo"><str>汥琠葷葬玥繙</str><str>ꓒꓴꓮ</str><str>ꛊꛣꚸꚩ꛷ꛖꛌꛉꛁꛢꛉꛋꛒꚺ꛲ꛚꚥ</str></arr></doc><doc><str name="id">263</str><int name="test_i_dvo">784672073</int></doc><doc><str name="id">264</str><double name="test_d_dvo">2.0810581938058295E-26</double></doc><doc><str name="id">265</str><long name="test_l_dvo">-5942286324001684341</long></doc><doc><str name="id">266</str><arr name="test_fs_dvo"><float>-3.11020585E10</float><float>-13.283813</float><float>-0.0020390744</float><float>-1.3303953E-8</float><float>4.8661044E-11</float><float>5.164787E-10</float><float>4.654424E-8</float><float>4.0034652E10</float><float>1.07592628E15</float><float>6.2009132E32</float></arr></doc><doc><str name="id">267</str><arr name="test_dts_dvo"><date>-2938-08-27T14:12:48.570Z</date><date>6363-03-02T22:10:08.699Z</date><date>6970-12-25T21:24:50.188Z</date><date>8342-01-23T00:33:37.676Z</date></arr></doc><doc><str name="id">268</str><arr name="test_ss_dv"><str>⟴⟿⟸⟺⟵⟰⟳⟾⟽⟷⟹⟰⟺⟸⟶⟶⟴⟿</str><str>ᰀᰈ᱄᱂</str><str>ᨍᨏ</str><str>ᧇ᧖ᧃᦏᦣᦆᦍᦶ</str><str>ᥱᥧ</str><str>ⷍⷑⷍⶡⶅⶎⷄⶠⷉⶊⷂ</str><str>𐃈𐃈𐂗𐃧𐃑𐃨𐃷𐃬</str><str>ꜯꝎꝦꝽꞸꞕꟻ</str><str>ἔἧὠἅὶἻ῾ᾱἻὺὝῴἦ</str><str>ⱻⱷⱱⱫ</str></arr></doc><doc><str name="id">269</str><str name="test_s_dvo2">▱◡◡▷▿▩</str></doc><doc><str name="id">270</str><str name="enum_dvo">High</str></doc><doc><str name="id">myid</str></doc></result>
</response>
request was:q=*:*&fl=*&wt=xml
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:940)
... 40 more
Build Log:
[...truncated 15785 lines...]
[junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_5492CE219E1804D1-001/init-core-data-001
[junit4] 2> 2235797 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 2235809 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2235811 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
[junit4] 2> 2235815 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
[junit4] 2> 2235816 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_5492CE219E1804D1-001/tempDir-001/collection1/conf/managed-schema
[junit4] 2> 2235872 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_5492CE219E1804D1-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml to /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_5492CE219E1804D1-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml.bak
[junit4] 2> 2235933 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2235934 WARN (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1ce0d27[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2235934 WARN (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1ce0d27[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2235955 WARN (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@11de803e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2235955 WARN (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@11de803e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2235968 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2235968 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2235983 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c3df612
[junit4] 2> 2235992 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c3df612
[junit4] 2> 2235992 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c3df612
[junit4] 2> 2235997 INFO (coreLoadExecutor-12577-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 2236003 INFO (coreLoadExecutor-12577-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
[junit4] 2> 2236007 INFO (coreLoadExecutor-12577-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
[junit4] 2> 2236007 INFO (coreLoadExecutor-12577-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_5492CE219E1804D1-001/tempDir-001/collection1, trusted=true
[junit4] 2> 2236007 INFO (coreLoadExecutor-12577-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@2c3df612
[junit4] 2> 2236007 INFO (coreLoadExecutor-12577-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_5492CE219E1804D1-001/tempDir-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_5492CE219E1804D1-001/init-core-data-001/]
[junit4] 2> 2236009 INFO (coreLoadExecutor-12577-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=41, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.36660502045722965]
[junit4] 2> 2236107 WARN (coreLoadExecutor-12577-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> 2236143 INFO (coreLoadExecutor-12577-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2236143 INFO (coreLoadExecutor-12577-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2236144 INFO (coreLoadExecutor-12577-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.532120899110497]
[junit4] 2> 2236145 INFO (coreLoadExecutor-12577-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@40884322[collection1] main]
[junit4] 2> 2236145 INFO (coreLoadExecutor-12577-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_5492CE219E1804D1-001/tempDir-001/collection1/conf
[junit4] 2> 2236145 INFO (coreLoadExecutor-12577-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2236146 INFO (searcherExecutor-12578-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@40884322[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2236146 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 2236146 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2236147 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2236148 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2236148 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
[junit4] 2> 2236464 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7db1e589[collection1] main]
[junit4] 2> 2236464 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2236465 INFO (searcherExecutor-12578-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7db1e589[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493470554}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2236465 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 316
[junit4] 2> 2236465 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2236466 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2236467 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2236469 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2236469 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2236470 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2236481 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2236481 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2236481 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
[junit4] 2> 2236871 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@164f7afb[collection1] main]
[junit4] 2> 2236871 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2236872 INFO (searcherExecutor-12578-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@164f7afb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493470554}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493470887}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2236872 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 390
[junit4] 2> 2236872 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2236872 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2236873 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2236873 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2236873 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2236873 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 2236874 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2236874 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2236874 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
[junit4] 2> 2237358 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@594ab60b[collection1] main]
[junit4] 2> 2237359 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2237359 INFO (searcherExecutor-12578-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@594ab60b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493470554}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493470887}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_2(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493471279}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2237359 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 485
[junit4] 2> 2237360 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:3&fl=test_ds_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2237360 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:3&fl=test_ds_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2237361 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2237362 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2237362 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:3&fl=test_ds_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2237363 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
[junit4] 2> 2237364 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 2237364 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2237364 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
[junit4] 2> 2237962 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@261ab390[collection1] main]
[junit4] 2> 2237962 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2237963 INFO (searcherExecutor-12578-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@261ab390[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493470554}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493470887}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_2(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493471279}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493471770}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2237963 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 598
[junit4] 2> 2237964 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&fl=test_ls_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2237964 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&fl=test_ls_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2237965 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2237966 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2237967 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&fl=test_ls_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2237968 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
[junit4] 2> 2237969 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 2237969 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2237970 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
[junit4] 2> 2238242 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6adc7694[collection1] main]
[junit4] 2> 2238242 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2238243 INFO (searcherExecutor-12578-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6adc7694[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493470554}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493470887}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_2(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493471279}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493471770}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493472375}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2238243 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 273
[junit4] 2> 2238243 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&fl=test_fs_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2238244 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&fl=test_fs_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2238244 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2238244 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2238245 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&fl=test_fs_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2238245 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
[junit4] 2> 2238246 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 2238246 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2238246 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
[junit4] 2> 2238372 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@90df86c[collection1] main]
[junit4] 2> 2238372 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2238373 INFO (searcherExecutor-12578-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@90df86c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493470554}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493470887}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_2(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493471279}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493471770}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493472375}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493472651}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2238373 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 126
[junit4] 2> 2238373 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2238374 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2238374 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2238374 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2238375 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2238375 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
[junit4] 2> 2238376 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 2238376 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2238376 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
[junit4] 2> 2238506 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2457511d[collection1] main]
[junit4] 2> 2238506 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2238507 INFO (searcherExecutor-12578-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2457511d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493470554}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493470887}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_2(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493471279}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493471770}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493472375}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493472651}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493472781}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2238507 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 131
[junit4] 2> 2238508 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2238508 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2238508 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2238508 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2238509 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2238509 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
[junit4] 2> 2238509 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 2238509 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2238510 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
[junit4] 2> 2238691 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4e8fe76c[collection1] main]
[junit4] 2> 2238692 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2238693 INFO (searcherExecutor-12578-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4e8fe76c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493470554}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493470887}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_2(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493471279}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493471770}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493472375}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493472651}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493472781}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493472915}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2238693 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 183
[junit4] 2> 2238693 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&fl=test_s_dvo2&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2238694 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&fl=test_s_dvo2,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2238694 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2238695 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2238695 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&fl=test_s_dvo2,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2238696 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
[junit4] 2> 2238697 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 2238697 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2238697 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
[junit4] 2> 2238970 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@39e5fc80[collection1] main]
[junit4] 2> 2238970 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2238971 INFO (searcherExecutor-12578-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@39e5fc80[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493470554}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493470887}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_2(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493471279}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493471770}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493472375}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493472651}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493472781}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493472915}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_8(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473102}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2238971 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 274
[junit4] 2> 2238972 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&fl=enums_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2238972 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&fl=enums_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2238981 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2238982 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2238982 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&fl=enums_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2238983 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=9 status=0 QTime=0
[junit4] 2> 2238984 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 2238984 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2238984 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[10]} 0 0
[junit4] 2> 2238984 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2238984 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
[junit4] 2> 2239044 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3bcb79b6[collection1] main]
[junit4] 2> 2239044 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2239045 INFO (searcherExecutor-12578-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3bcb79b6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473389}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2239049 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 64
[junit4] 2> 2239049 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2239050 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2239050 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2239051 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2239052 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2239052 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2239053 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[11]} 0 0
[junit4] 2> 2239053 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2239053 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
[junit4] 2> 2239136 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@15bd5034[collection1] main]
[junit4] 2> 2239137 INFO (searcherExecutor-12578-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@15bd5034[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473389}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_a(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473458}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2239137 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2239138 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 84
[junit4] 2> 2239138 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:11&fl=test_is_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2239139 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:11&fl=test_is_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2239139 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2239139 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2239140 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:11&fl=test_is_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2239140 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 2239141 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[12]} 0 0
[junit4] 2> 2239141 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2239141 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
[junit4] 2> 2239241 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@682cfc6[collection1] main]
[junit4] 2> 2239241 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2239242 INFO (searcherExecutor-12578-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@682cfc6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473389}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_a(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473458}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_b(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473546}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2239242 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 101
[junit4] 2> 2239242 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2239243 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2239243 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2239243 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2239244 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2239244 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
[junit4] 2> 2239245 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[13]} 0 0
[junit4] 2> 2239245 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2239245 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
[junit4] 2> 2239349 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5d7d940[collection1] main]
[junit4] 2> 2239349 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2239350 INFO (searcherExecutor-12578-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5d7d940[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473389}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_a(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473458}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_b(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473546}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_c(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473650}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2239350 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 104
[junit4] 2> 2239350 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:13&fl=test_ls_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2239351 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:13&fl=test_ls_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2239351 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2239352 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2239353 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:13&fl=test_ls_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2239354 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
[junit4] 2> 2239355 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[14]} 0 0
[junit4] 2> 2239355 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2239355 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
[junit4] 2> 2239472 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@559a4a46[collection1] main]
[junit4] 2> 2239473 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2239473 INFO (searcherExecutor-12578-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@559a4a46[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473389}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_a(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473458}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_b(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473546}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_c(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473650}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_d(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473760}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2239473 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 118
[junit4] 2> 2239474 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&fl=test_fs_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2239474 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&fl=test_fs_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2239474 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2239475 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2239475 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&fl=test_fs_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2239475 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
[junit4] 2> 2239476 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[15]} 0 0
[junit4] 2> 2239477 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2239477 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
[junit4] 2> 2239616 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@60884de9[collection1] main]
[junit4] 2> 2239618 INFO (searcherExecutor-12578-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@60884de9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473389}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_a(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473458}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_b(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473546}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_c(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473650}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_d(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473760}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_e(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473882}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2239618 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2239618 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 141
[junit4] 2> 2239618 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:15&fl=test_dt_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2239619 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:15&fl=test_dt_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2239619 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2239619 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] 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> 2239620 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:15&fl=test_dt_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2239620 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
[junit4] 2> 2239621 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[16]} 0 0
[junit4] 2> 2239621 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2239621 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@26166d1f commitCommandVersion:0
[junit4] 2> 2239850 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@51af2723[collection1] main]
[junit4] 2> 2239850 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[5492CE219E1804D1]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2239851 INFO (searcherExecutor-12578-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@51af2723[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473389}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_a(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473458}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_b(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473546}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_c(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473650}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_d(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473760}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_e(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-generic, timestamp=1562493473882}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_f(8.2.0):C1:[diagnostics={os=Linux, java.vendor=AdoptOpenJDK, java.version=11.0.3, java.vm.version=11.0.3+7, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=11.0.3+7, source=flush, os.version=4.18.0-24-gene
[...truncated too long message...]
7edb
[junit4] 2> 2294748 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[5492CE219E1804D1]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2294748 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[5492CE219E1804D1]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1be01e26: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@27b2d165
[junit4] 2> 2294752 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[5492CE219E1804D1]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2294752 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[5492CE219E1804D1]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@66b5b680: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2e0183d5
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestUseDocValuesAsStored_5492CE219E1804D1-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {}, docValues:{}, maxPointsInLeafNode=622, maxMBSortInHeap=5.114769350133044, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@7f3e7fe1), locale=fr-KM, timezone=America/Grenada
[junit4] 2> NOTE: Linux 4.18.0-24-generic amd64/AdoptOpenJDK 11.0.3 (64-bit)/cpus=8,threads=1,free=146912216,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [FieldAnalysisRequestHandlerTest, TestReloadAndDeleteDocs, PingRequestHandlerTest, UniqFieldsUpdateProcessorFactoryTest, TestLegacyNumericUtils, TestMacroExpander, TestJsonFacetsStatsParsing, MergeStrategyTest, HdfsDirectoryTest, IgnoreLargeDocumentProcessorFactoryTest, TestImplicitCoreProperties, TestRequestForwarding, SimpleCollectionCreateDeleteTest, TestDynamicFieldResource, TestFoldingMultitermQuery, AnalysisErrorHandlingTest, TestReloadDeadlock, CollectionStateFormat2Test, TestPointFields, TestInPlaceUpdatesDistrib, TestCursorMarkWithoutUniqueKey, CheckHdfsIndexTest, TestStressCloudBlindAtomicUpdates, TestCryptoKeys, AutoScalingHandlerTest, TestIBSimilarityFactory, ResponseHeaderTest, TestManagedSchemaAPI, TestDynamicLoading, NotRequiredUniqueKeyTest, PrimitiveFieldTypeTest, TestHashPartitioner, TestRangeQuery, AuditLoggerPluginTest, TestLockTree, TestSQLHandlerNonCloud, BlobRepositoryCloudTest, RecoveryAfterSoftCommitTest, TestSolrIndexConfig, TestDistributedTracing, TestApiFramework, TolerantUpdateProcessorTest, TestUtilizeNode, TestSimExtremeIndexing, DisMaxRequestHandlerTest, TestTlogReplica, TestDefaultStatsCache, TestIndexingPerformance, TestBlendedInfixSuggestions, TestTrackingShardHandlerFactory, PreAnalyzedUpdateProcessorTest, TestClassicSimilarityFactory, TestSolrCloudWithDelegationTokens, TestElisionMultitermQuery, TestSha256AuthenticationProvider, TestNoOpRegenerator, TestConfigSetsAPI, HdfsTlogReplayBufferedWhileIndexingTest, TestUtils, SuggesterFSTTest, RoutingToNodesWithPropertiesTest, TestManagedResource, DistributedFacetPivotSmallTest, TestHdfsCloudBackupRestore, TestZkChroot, SolrCoreCheckLockOnStartupTest, PhrasesIdentificationComponentTest, CdcrWithNodesRestartsTest, DocValuesTest, SchemaVersionSpecificBehaviorTest, TestHighlightDedupGrouping, TestSimpleQParserPlugin, TestFieldCacheWithThreads, LegacyCloudClusterPropTest, DirectSolrConnectionTest, ConfigSetsAPITest, NodeAddedTriggerTest, TokenizerChainTest, TestDistributedMap, HttpPartitionTest, SolrCoreMetricManagerTest, StatelessScriptUpdateProcessorFactoryTest, CloudMLTQParserTest, ZkControllerTest, TestMinHashQParser, TestFieldTypeCollectionResource, SimpleFacetsTest, CollectionsAPIAsyncDistributedZkTest, TestSimTriggerIntegration, DeleteNodeTest, SolrPluginUtilsTest, ProtectedTermFilterFactoryTest, SpellCheckCollatorTest, DistributedSpellCheckComponentTest, TestSimDistribStateManager, LoggingHandlerTest, TestCloudJSONFacetSKG, TestManagedSynonymFilterFactory, TestComponentsName, TestJavabinTupleStreamParser, SpellingQueryConverterTest, DistributedDebugComponentTest, TestFieldSortValues, SmileWriterTest, OrderedExecutorTest, SolrIndexMetricsTest, BasicFunctionalityTest, DirectSolrSpellCheckerTest, TestCorePropertiesReload, SearchHandlerTest, TestFaceting, TestSlowCompositeReaderWrapper, MetricTriggerIntegrationTest, TestCloudManagedSchema, TestStreamBody, RuleEngineTest, StressHdfsTest, VersionInfoTest, TestXmlQParser, TestNRTOpen, TriggerCooldownIntegrationTest, TestSolrCLIRunExample, BinaryUpdateRequestHandlerTest, CachingDirectoryFactoryTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestAuthorizationFramework, ZkStateReaderTest, TestSystemCollAutoCreate, TestExactStatsCache, UUIDFieldTest, TestIndexSearcher, AtomicUpdateRemovalJavabinTest, MigrateRouteKeyTest, SynonymTokenizerTest, TestCollapseQParserPlugin, FacetPivotSmallTest, DirectoryFactoryTest, TestStressReorder, TestRequestStatusCollectionAPI, ConnectionReuseTest, TestNumericTerms64, EchoParamsTest, MinimalSchemaTest, OutputWriterTest, SampleTest, SolrInfoBeanTest, SolrTestCaseJ4Test, TestDistributedMissingSort, TestDistributedSearch, TestDocumentBuilder, TestRandomDVFaceting, TestRandomFaceting, TestSimpleTrackingShardHandler, TestSolrCoreProperties, TestTolerantSearch, TestTrie, TestDeprecatedFilters, TestLuceneMatchVersion, TestReversedWildcardFilterFactory, TestEmbeddedSolrServerAdminHandler, TestEmbeddedSolrServerConstructors, TestEmbeddedSolrServerSchemaAPI, AddReplicaTest, AliasIntegrationTest, AssignBackwardCompatibilityTest, BasicDistributedZk2Test, BasicDistributedZkTest, BasicZkTest, ChaosMonkeyNothingIsSafeTest, ChaosMonkeySafeLeaderTest, CleanupOldIndexTest, CloudExitableDirectoryReaderTest, CreateRoutedAliasTest, DeleteShardTest, DistribCursorPagingTest, DistribJoinFromCollectionTest, DistributedQueueTest, DistributedVersionInfoTest, LeaderElectionTest, LeaderFailoverAfterPartitionTest, LeaderTragicEventTest, MoveReplicaHDFSFailoverTest, MoveReplicaHDFSTest, MoveReplicaTest, OverseerRolesTest, OverseerTaskQueueTest, ReindexCollectionTest, SharedFSAutoReplicaFailoverTest, SliceStateTest, SolrCLIZkUtilsTest, SolrXmlInZkTest, SplitShardTest, TestAuthenticationFramework, TestCloudDeleteByQuery, TestCloudInspectUtil, TestCloudPhrasesIdentificationComponent, TestCloudPivotFacet, TestCloudRecovery, TestPrepRecovery, TestRandomFlRTGCloud, TestShortCircuitedRequests, VMParamsZkACLAndCredentialsProvidersTest, ZkSolrClientTest, ConcurrentDeleteAndCreateCollectionTest, CustomCollectionTest, NodeLostTriggerTest, ScheduledTriggerIntegrationTest, SystemLogListenerTest, TestSimClusterStateProvider, TestSimNodeAddedTrigger, TestSimNodeLostTrigger, BaseCdcrDistributedZkTest, CdcrBidirectionalTest, CdcrBootstrapTest, HdfsRecoveryZkTest, HdfsRestartWhileUpdatingTest, HdfsSyncSliceTest, HdfsDirectoryFactoryTest, RAMDirectoryFactoryTest, RequestHandlersTest, ResourceLoaderTest, TestBackupRepositoryFactory, TestBadConfig, TestConfig, TestConfigSetImmutable, TestJmxIntegration, TestSimpleTextCodec, TestSolrConfigHandler, TestConfigReload, TestReplicationHandler, TestConfigsApi, ZookeeperStatusHandlerTest, CustomTermsComponentTest, DistributedQueryComponentCustomSortTest, ShardsWhitelistTest, StatsComponentTest, TermVectorComponentTest, TermsComponentTest, TestDistributedStatsComponentCardinality, TestExpandComponent, TestHttpShardHandlerFactory, JavabinLoaderTest, RandomizedTaggerTest, TaggerTest, XmlInterpolationTest, HighlighterConfigTest, HighlighterTest, TestNumericTokenStream, TestLogWatcher, JvmMetricsTest, SolrMetricsIntegrationTest, SolrJmxReporterCloudTest, SolrShardReporterTest, TestCSVResponseWriter, TestCustomDocTransformer, TestGeoJSONResponseWriter, TestPushWriter, TestRawResponseWriter, TestRetrieveFieldsOptimizer, TestChildDocTransformerHierarchy, TestSubQueryTransformerCrossCore, TestBulkSchemaAPI, TestUniqueKeyFieldResource, ChangedSchemaMergeTest, CurrencyFieldTypeTest, ExternalFileFieldSortTest, IndexSchemaTest, TestCollationField, TestHalfAndHalfDocValues, TestSortableTextField, TestUseDocValuesAsStored]
[junit4] Completed [751/867 (1!)] on J1 in 59.06s, 7 tests, 2 errors <<< FAILURES!
[...truncated 45206 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/835/consoleText
[repro] Revision: 5d3a84fcd0f3d4bded24e6db0c78bbdcba6f3b2a
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=TestUseDocValuesAsStored -Dtests.method=testMultipleSearchResults -Dtests.seed=5492CE219E1804D1 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr-KM -Dtests.timezone=America/Grenada -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[repro] Repro line: ant test -Dtestcase=TestUseDocValuesAsStored -Dtests.method=testDuplicateMultiValued -Dtests.seed=5492CE219E1804D1 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr-KM -Dtests.timezone=America/Grenada -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TestUseDocValuesAsStored
[repro] ant compile-test
[...truncated 3582 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestUseDocValuesAsStored" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=5492CE219E1804D1 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr-KM -Dtests.timezone=America/Grenada -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[...truncated 119 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.schema.TestUseDocValuesAsStored
[repro] Exiting with code 0
[...truncated 43 lines...]