lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-BadApples-Tests-master - Build # 405 - Still Failing
Date Fri, 05 Jul 2019 06:27:31 GMT
Build: https://builds.apache.org/job/Lucene-Solr-BadApples-Tests-master/405/

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

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
	at __randomizedtesting.SeedInfo.seed([616067DC51E2DFFA:8FBD73FC9F532946]: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:165)
	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_dv']/str[.='X']
	xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">8</int></lst><result name="response" numFound="11" start="0"><doc><str name="id">172</str><str name="test_s_dvo">૳઻૗૞ૢ૱આ૕ધઌલોઅ૩ઌ</str></doc><doc><str name="id">173</str><int name="test_i_dvo">23955879</int></doc><doc><str name="id">174</str><double name="test_d_dvo">-1.8691433783734868E24</double></doc><doc><str name="id">175</str><long name="test_l_dvo">1086115846809346447</long></doc><doc><str name="id">176</str><arr name="test_fs_dvo"><float>-7.975666E28</float><float>-9.8651027E27</float><float>-2.59363599E15</float><float>-207.27339</float><float>-3.2992194</float><float>-2.6420107E-20</float><float>37065.457</float><float>5.4336337E28</float></arr></doc><doc><str name="id">177</str><arr name="test_dts_dvo"><date>-10336-08-26T00:29:21.909Z</date><date>0859-11-21T23:28:27.477Z</date><date>2709-09-13T12:22:58.715Z</date><date>4548-02-14T00:35:03.256Z</date><date>5971-03-02T23:14:26.107Z</date><date>6011-07-30T10:34:42.579Z</date><date>6229-06-20T13:30:02.876Z</date><date>8938-09-07T07:38:44.437Z</date><date>9416-11-30T21:10:48.753Z</date><date>+10795-12-22T16:02:04.092Z</date></arr></doc><doc><str name="id">178</str><str name="test_s_dv">ᨊᨂᨇᨊᨁᨙᨆᨗ᨜᨟᨜ᨖᨖᨓᨛ</str></doc><doc><str name="id">179</str><str name="test_s_dvo2">ꠟꠔꠏ꠬꠬ꠣꠏꠘꠎꠎ</str></doc><doc><str name="id">180</str><arr name="enums_dvo"><str>Not Available</str><str>Low</str><str>x9</str><str>x12</str><str>x12</str><str>x15</str><str>x16</str><str>x18</str><str>x18</str><str>x18</str></arr></doc><doc><str name="id">1</str><arr name="test_ss_dvo"><str>X</str><str>Y</str></arr></doc></result>
</response>

	request was:q=*:*&fl=*&wt=xml
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:940)
	... 40 more




Build Log:
[...truncated 15149 lines...]
   [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
   [junit4]   2> 1536635 INFO  (SUITE-TestUseDocValuesAsStored-seed#[616067DC51E2DFFA]-worker) [     ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/init-core-data-001
   [junit4]   2> 1536636 INFO  (SUITE-TestUseDocValuesAsStored-seed#[616067DC51E2DFFA]-worker) [     ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 1536637 INFO  (SUITE-TestUseDocValuesAsStored-seed#[616067DC51E2DFFA]-worker) [     ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
   [junit4]   2> 1536653 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.SolrTestCaseJ4 ###Starting testOnEmptyIndex
   [junit4]   2> 1536657 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1536666 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 1536680 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] 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> 1536682 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1536689 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
   [junit4]   2> 1536690 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-001/collection1/conf/managed-schema
   [junit4]   2> 1536694 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1536785 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1536788 WARN  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1c05439d[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1536788 WARN  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1c05439d[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1536791 WARN  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1ca0218a[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1536791 WARN  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1ca0218a[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1536822 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1536822 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 1536849 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@54f30e3d
   [junit4]   2> 1536868 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@54f30e3d
   [junit4]   2> 1536868 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@54f30e3d
   [junit4]   2> 1536875 INFO  (coreLoadExecutor-6646-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 1536893 INFO  (coreLoadExecutor-6646-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
   [junit4]   2> 1536900 INFO  (coreLoadExecutor-6646-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
   [junit4]   2> 1536900 INFO  (coreLoadExecutor-6646-thread-1) [    x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-001/collection1, trusted=true
   [junit4]   2> 1536901 INFO  (coreLoadExecutor-6646-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@54f30e3d
   [junit4]   2> 1536901 INFO  (coreLoadExecutor-6646-thread-1) [    x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/init-core-data-001/]
   [junit4]   2> 1536905 INFO  (coreLoadExecutor-6646-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=16.7158203125, floorSegmentMB=1.3056640625, forceMergeDeletesPctAllowed=28.933457701162975, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5735169985703813, deletesPctAllowed=43.67221013527262
   [junit4]   2> 1536922 WARN  (coreLoadExecutor-6646-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> 1536996 INFO  (coreLoadExecutor-6646-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1536996 INFO  (coreLoadExecutor-6646-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1536998 INFO  (coreLoadExecutor-6646-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=25, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 1536999 INFO  (coreLoadExecutor-6646-thread-1) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@53acc4aa[collection1] main]
   [junit4]   2> 1536999 INFO  (coreLoadExecutor-6646-thread-1) [    x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-001/collection1/conf
   [junit4]   2> 1537000 INFO  (coreLoadExecutor-6646-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1537001 INFO  (searcherExecutor-6647-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@53acc4aa[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1537003 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1537003 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1537003 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] 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> 1537003 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1537003 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@af61afd commitCommandVersion:0
   [junit4]   2> 1537010 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6220c657[collection1] main]
   [junit4]   2> 1537010 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1537012 INFO  (searcherExecutor-6647-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6220c657[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1537012 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 8
   [junit4]   2> 1537017 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=3
   [junit4]   2> 1537018 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1537018 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1537018 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*,test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1537019 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[xyz]} 0 0
   [junit4]   2> 1537019 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1537020 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1537020 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1537020 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*,test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1537020 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1537020 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@af61afd commitCommandVersion:0
   [junit4]   2> 1537062 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@433590f8[collection1] main]
   [junit4]   2> 1537065 INFO  (searcherExecutor-6647-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@433590f8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306567340, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1537065 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1537065 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 44
   [junit4]   2> 1537066 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1537066 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1537067 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=test_nonstored_dv_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1537067 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*,test_nonstored_dv_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1537068 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[xyz]} 0 0
   [junit4]   2> 1537068 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1537068 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@af61afd commitCommandVersion:0
   [junit4]   2> 1537097 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4ee0b944[collection1] main]
   [junit4]   2> 1537099 INFO  (searcherExecutor-6647-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4ee0b944[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306567387, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1537099 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1537100 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 32
   [junit4]   2> 1537101 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1537102 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1537102 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] 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> 1537102 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1537102 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2058356410
   [junit4]   2> 1537102 INFO  (coreCloseExecutor-6651-thread-1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@7092cb78
   [junit4]   2> 1537102 INFO  (coreCloseExecutor-6651-thread-1) [    x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=7092cb78
   [junit4]   2> 1537102 INFO  (coreCloseExecutor-6651-thread-1) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@46f09c2: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@6fe601ae
   [junit4]   2> 1537150 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 1537150 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6b4553f4: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@61d6fd12
   [junit4]   2> 1537160 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 1537160 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4f7e4ea: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@4dc29da0
   [junit4]   2> 1537167 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 1537167 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@192a691a: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1baa4a65
   [junit4]   2> 1537169 INFO  (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[616067DC51E2DFFA]) [     ] o.a.s.SolrTestCaseJ4 ###Ending testOnEmptyIndex
   [junit4]   2> 1537173 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.SolrTestCaseJ4 ###Starting testMultivaluedOrdering
   [junit4]   2> 1537177 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1537184 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 1537195 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] 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> 1537197 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1537205 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
   [junit4]   2> 1537205 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-002/collection1/conf/managed-schema
   [junit4]   2> 1537208 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1537483 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1537488 WARN  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6bc3ab49[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1537488 WARN  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6bc3ab49[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1537491 WARN  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@59f1d284[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1537491 WARN  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@59f1d284[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1537510 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1537510 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 1537556 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@54f30e3d
   [junit4]   2> 1537591 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@54f30e3d
   [junit4]   2> 1537591 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@54f30e3d
   [junit4]   2> 1537598 INFO  (coreLoadExecutor-6661-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 1537610 INFO  (coreLoadExecutor-6661-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
   [junit4]   2> 1537617 INFO  (coreLoadExecutor-6661-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
   [junit4]   2> 1537620 INFO  (coreLoadExecutor-6661-thread-1) [    x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-002/collection1, trusted=true
   [junit4]   2> 1537620 INFO  (coreLoadExecutor-6661-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@54f30e3d
   [junit4]   2> 1537621 INFO  (coreLoadExecutor-6661-thread-1) [    x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-002/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/init-core-data-001/]
   [junit4]   2> 1537632 WARN  (coreLoadExecutor-6661-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> 1537752 INFO  (coreLoadExecutor-6661-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1537752 INFO  (coreLoadExecutor-6661-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1537754 INFO  (coreLoadExecutor-6661-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=16.7158203125, floorSegmentMB=1.3056640625, forceMergeDeletesPctAllowed=28.933457701162975, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5735169985703813, deletesPctAllowed=43.67221013527262
   [junit4]   2> 1537785 INFO  (coreLoadExecutor-6661-thread-1) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@68f7af23[collection1] main]
   [junit4]   2> 1537786 INFO  (coreLoadExecutor-6661-thread-1) [    x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-002/collection1/conf
   [junit4]   2> 1537787 INFO  (coreLoadExecutor-6661-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1537788 INFO  (searcherExecutor-6662-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@68f7af23[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306567387, os=Linux, java.vendor=Oracle Corporation, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1537788 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1537789 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1537789 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] 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> 1537790 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 1537790 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 1537790 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 1537791 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[4]} 0 0
   [junit4]   2> 1537791 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1537791 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4a501993 commitCommandVersion:0
   [junit4]   2> 1537911 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3fe5862c[collection1] main]
   [junit4]   2> 1537913 INFO  (searcherExecutor-6662-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3fe5862c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):C4:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306568188, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1537913 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1537914 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 123
   [junit4]   2> 1537916 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:1&fl=test_mvt_dvt_st_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1537917 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:2&fl=test_mvt_dvt_sf_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1537917 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:3&fl=test_mvt_dvf_st_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1537918 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:4&fl=test_mvt_dvu_st_str&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1537918 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1537918 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] 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> 1537918 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1537918 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=296306657
   [junit4]   2> 1537930 INFO  (coreCloseExecutor-6666-thread-1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@6efba14b
   [junit4]   2> 1537930 INFO  (coreCloseExecutor-6666-thread-1) [    x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=6efba14b
   [junit4]   2> 1537930 INFO  (coreCloseExecutor-6666-thread-1) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1a988f37: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@6467df41
   [junit4]   2> 1537962 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 1537963 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5dba25a1: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@7c26bd39
   [junit4]   2> 1537997 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 1537997 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@19511b05: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@4dc29da0
   [junit4]   2> 1538008 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 1538009 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1a81cf5d: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1baa4a65
   [junit4]   2> 1538022 INFO  (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[616067DC51E2DFFA]) [     ] o.a.s.SolrTestCaseJ4 ###Ending testMultivaluedOrdering
   [junit4]   2> 1538033 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.SolrTestCaseJ4 ###Starting testMultipleSearchResults
   [junit4]   2> 1538037 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1538154 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 1538165 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] 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> 1538170 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1538180 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
   [junit4]   2> 1538191 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-003/collection1/conf/managed-schema
   [junit4]   2> 1538194 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1538765 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1538766 WARN  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2e05a2[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1538766 WARN  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2e05a2[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1538769 WARN  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@73631d38[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1538769 WARN  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@73631d38[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1538788 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1538788 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 1538813 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@54f30e3d
   [junit4]   2> 1538834 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@54f30e3d
   [junit4]   2> 1538834 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@54f30e3d
   [junit4]   2> 1538852 INFO  (coreLoadExecutor-6676-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 1538871 INFO  (coreLoadExecutor-6676-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
   [junit4]   2> 1538881 INFO  (coreLoadExecutor-6676-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
   [junit4]   2> 1538881 INFO  (coreLoadExecutor-6676-thread-1) [    x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-003/collection1, trusted=true
   [junit4]   2> 1538881 INFO  (coreLoadExecutor-6676-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@54f30e3d
   [junit4]   2> 1538882 INFO  (coreLoadExecutor-6676-thread-1) [    x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-003/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/init-core-data-001/]
   [junit4]   2> 1538885 WARN  (coreLoadExecutor-6676-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> 1538965 INFO  (coreLoadExecutor-6676-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1538965 INFO  (coreLoadExecutor-6676-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1538967 INFO  (coreLoadExecutor-6676-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=16.7158203125, floorSegmentMB=1.3056640625, forceMergeDeletesPctAllowed=28.933457701162975, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5735169985703813, deletesPctAllowed=43.67221013527262
   [junit4]   2> 1538971 INFO  (coreLoadExecutor-6676-thread-1) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5881d445[collection1] main]
   [junit4]   2> 1538971 INFO  (coreLoadExecutor-6676-thread-1) [    x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-003/collection1/conf
   [junit4]   2> 1538972 INFO  (coreLoadExecutor-6676-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1538973 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1538973 INFO  (searcherExecutor-6677-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5881d445[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):C4:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306568188, os=Linux, java.vendor=Oracle Corporation, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1538975 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[myid1]} 0 0
   [junit4]   2> 1538975 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[myid2]} 0 0
   [junit4]   2> 1538976 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[myid3]} 0 0
   [junit4]   2> 1538976 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[myid4]} 0 0
   [junit4]   2> 1538976 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[myid5]} 0 0
   [junit4]   2> 1538977 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[myid6]} 0 0
   [junit4]   2> 1538977 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1538977 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7afebbb2 commitCommandVersion:0
   [junit4]   2> 1539045 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@45fb77b0[collection1] main]
   [junit4]   2> 1539046 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1539048 INFO  (searcherExecutor-6677-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@45fb77b0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):C4:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306568188, os=Linux, java.vendor=Oracle Corporation, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(9.0.0):C6:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306569298, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1539048 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 70
   [junit4]   2> 1539050 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:myid*&fl=*&wt=xml} hits=6 status=0 QTime=1
   [junit4]   2> 1539051 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1539053 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] 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 1
   [junit4]   2> 1539053 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1539053 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1347286057
   [junit4]   2> 1539069 INFO  (coreCloseExecutor-6681-thread-1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@7ac1045f
   [junit4]   2> 1539069 INFO  (coreCloseExecutor-6681-thread-1) [    x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=7ac1045f
   [junit4]   2> 1539069 INFO  (coreCloseExecutor-6681-thread-1) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@e368e23: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2d4ea750
   [junit4]   2> 1539101 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 1539102 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@58a2b216: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@20d124fb
   [junit4]   2> 1539112 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 1539117 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1aae1989: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@4dc29da0
   [junit4]   2> 1539129 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 1539129 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@53f6b9dc: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1baa4a65
   [junit4]   2> 1539136 INFO  (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[616067DC51E2DFFA]) [     ] o.a.s.SolrTestCaseJ4 ###Ending testMultipleSearchResults
   [junit4]   2> 1539140 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued
   [junit4]   2> 1539143 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1539160 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 1539172 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] 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> 1539174 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
   [junit4]   2> 1539180 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
   [junit4]   2> 1539181 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-004/collection1/conf/managed-schema
   [junit4]   2> 1539186 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-004/collection1/conf/schema-non-stored-docvalues.xml to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-004/collection1/conf/schema-non-stored-docvalues.xml.bak
   [junit4]   2> 1539502 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1539510 WARN  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@46d2b424[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1539510 WARN  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@46d2b424[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1539513 WARN  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7100598f[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1539513 WARN  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7100598f[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1539535 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1539535 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 1539572 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@54f30e3d
   [junit4]   2> 1539591 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@54f30e3d
   [junit4]   2> 1539591 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@54f30e3d
   [junit4]   2> 1539599 INFO  (coreLoadExecutor-6691-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 1539614 INFO  (coreLoadExecutor-6691-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
   [junit4]   2> 1539621 INFO  (coreLoadExecutor-6691-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
   [junit4]   2> 1539621 INFO  (coreLoadExecutor-6691-thread-1) [    x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-004/collection1, trusted=true
   [junit4]   2> 1539621 INFO  (coreLoadExecutor-6691-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@54f30e3d
   [junit4]   2> 1539621 INFO  (coreLoadExecutor-6691-thread-1) [    x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-004/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/init-core-data-001/]
   [junit4]   2> 1539637 WARN  (coreLoadExecutor-6691-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> 1539745 INFO  (coreLoadExecutor-6691-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1539745 INFO  (coreLoadExecutor-6691-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1539747 INFO  (coreLoadExecutor-6691-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=16.7158203125, floorSegmentMB=1.3056640625, forceMergeDeletesPctAllowed=28.933457701162975, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5735169985703813, deletesPctAllowed=43.67221013527262
   [junit4]   2> 1539752 INFO  (coreLoadExecutor-6691-thread-1) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5de398e8[collection1] main]
   [junit4]   2> 1539752 INFO  (coreLoadExecutor-6691-thread-1) [    x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_616067DC51E2DFFA-001/tempDir-004/collection1/conf
   [junit4]   2> 1539753 INFO  (coreLoadExecutor-6691-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1539754 INFO  (searcherExecutor-6692-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5de398e8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):C4:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306568188, os=Linux, java.vendor=Oracle Corporation, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(9.0.0):C6:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306569298, os=Linux, java.vendor=Oracle Corporation, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1539755 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1539756 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1539756 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] 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> 1539796 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 1539813 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1539813 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3d0c3fa8 commitCommandVersion:0
   [junit4]   2> 1539889 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6eacafa8[collection1] main]
   [junit4]   2> 1539890 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1539892 INFO  (searcherExecutor-6692-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6eacafa8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306570132, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1539906 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 109
   [junit4]   2> 1539907 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] 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> 1539909 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] 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> 1539911 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] 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> 1539912 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] 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> 1539914 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] 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> 1539915 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1539917 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 1539918 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1539918 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3d0c3fa8 commitCommandVersion:0
   [junit4]   2> 1539979 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@69347f2a[collection1] main]
   [junit4]   2> 1539980 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1539982 INFO  (searcherExecutor-6692-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@69347f2a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306570132, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306570237, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1539982 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 64
   [junit4]   2> 1539983 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:2&fl=test_i_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1539984 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:2&fl=test_i_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1539984 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] 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> 1539985 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] 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> 1539985 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:2&fl=test_i_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1539986 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 1539988 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 1539988 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1539988 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3d0c3fa8 commitCommandVersion:0
   [junit4]   2> 1540046 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c8a817e[collection1] main]
   [junit4]   2> 1540047 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1540049 INFO  (searcherExecutor-6692-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c8a817e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306570132, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306570237, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306570307, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1540049 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 61
   [junit4]   2> 1540050 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] 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> 1540051 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] 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> 1540052 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] 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> 1540056 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] 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> 1540057 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] 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> 1540058 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 1540060 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[4]} 0 0
   [junit4]   2> 1540060 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1540060 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3d0c3fa8 commitCommandVersion:0
   [junit4]   2> 1540106 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1acf2f96[collection1] main]
   [junit4]   2> 1540115 INFO  (searcherExecutor-6692-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1acf2f96[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306570132, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306570237, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306570307, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306570379, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1540115 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1540115 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 55
   [junit4]   2> 1540116 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:4&fl=test_l_dvo&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1540117 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:4&fl=test_l_dvo,*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1540118 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] 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> 1540118 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] 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> 1540126 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:4&fl=test_l_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1540126 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 1540128 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[5]} 0 0
   [junit4]   2> 1540128 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1540128 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3d0c3fa8 commitCommandVersion:0
   [junit4]   2> 1540178 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6738e415[collection1] main]
   [junit4]   2> 1540179 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1540181 INFO  (searcherExecutor-6692-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6738e415[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306570132, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306570237, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306570307, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306570379, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_8(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.1+13-LTS, java.version=11.0.1, timestamp=1562306570459, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-137-generic, java.runtime.version=11.0.1+13-LTS}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1540181 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 53
   [junit4]   2> 1540182 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] 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> 1540183 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] 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> 1540184 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] 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> 1540185 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] 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> 1540186 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] 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> 1540187 INFO  (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[616067DC51E2DFFA]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1540189 INFO

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

re/SolrResourceLoader.java (at line 20)
 [ecj-lint] 	import javax.naming.InitialContext;
 [ecj-lint] 	       ^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] The type javax.naming.InitialContext is not accessible
 [ecj-lint] ----------
 [ecj-lint] 6. ERROR in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/core/SolrResourceLoader.java (at line 21)
 [ecj-lint] 	import javax.naming.NamingException;
 [ecj-lint] 	       ^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] The type javax.naming.NamingException is not accessible
 [ecj-lint] ----------
 [ecj-lint] 7. ERROR in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/core/SolrResourceLoader.java (at line 22)
 [ecj-lint] 	import javax.naming.NoInitialContextException;
 [ecj-lint] 	       ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] The type javax.naming.NoInitialContextException is not accessible
 [ecj-lint] ----------
 [ecj-lint] 8. ERROR in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/core/SolrResourceLoader.java (at line 776)
 [ecj-lint] 	Context c = new InitialContext();
 [ecj-lint] 	^^^^^^^
 [ecj-lint] Context cannot be resolved to a type
 [ecj-lint] ----------
 [ecj-lint] 9. ERROR in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/core/SolrResourceLoader.java (at line 776)
 [ecj-lint] 	Context c = new InitialContext();
 [ecj-lint] 	                ^^^^^^^^^^^^^^
 [ecj-lint] InitialContext cannot be resolved to a type
 [ecj-lint] ----------
 [ecj-lint] 10. ERROR in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/core/SolrResourceLoader.java (at line 779)
 [ecj-lint] 	} catch (NoInitialContextException e) {
 [ecj-lint] 	         ^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] NoInitialContextException cannot be resolved to a type
 [ecj-lint] ----------
 [ecj-lint] 11. ERROR in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/core/SolrResourceLoader.java (at line 781)
 [ecj-lint] 	} catch (NamingException e) {
 [ecj-lint] 	         ^^^^^^^^^^^^^^^
 [ecj-lint] NamingException cannot be resolved to a type
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 12. WARNING in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/handler/SolrConfigHandler.java (at line 535)
 [ecj-lint] 	new PluginBag.RuntimeLib(req.getCore()).init(new PluginInfo(info.tag, op.getDataMap()));
 [ecj-lint] 	^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 13. WARNING in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/handler/admin/SegmentsInfoRequestHandler.java (at line 244)
 [ecj-lint] 	leafReader = ((FilterLeafReader)leafReader).getDelegate();
 [ecj-lint] 	^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: 'leafReader' is not closed at this location
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 14. WARNING in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/handler/component/HttpShardHandler.java (at line 148)
 [ecj-lint] 	return new JavaBinCodec(null, stringCache).setReadStringAsCharSeq(true);
 [ecj-lint] 	       ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 15. WARNING in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/handler/loader/JavabinLoader.java (at line 137)
 [ecj-lint] 	new JavaBinCodec() {
 [ecj-lint]       SolrParams params;
 [ecj-lint]       AddUpdateCommand addCmd = null;
 [ecj-lint] 
 [ecj-lint]       @Override
 [ecj-lint]       public List<Object> readIterator(DataInputInputStream fis) throws IOException {
 [ecj-lint]         while (true) {
 [ecj-lint]           Object o = readVal(fis);
 [ecj-lint]           if (o == END_OBJ) break;
 [ecj-lint]           if (o instanceof NamedList) {
 [ecj-lint]             params = ((NamedList) o).toSolrParams();
 [ecj-lint]           } else {
 [ecj-lint]             try {
 [ecj-lint]               if (o instanceof byte[]) {
 [ecj-lint]                 if (params != null) req.setParams(params);
 [ecj-lint]                 byte[] buf = (byte[]) o;
 [ecj-lint]                 contentStreamLoader.load(req, rsp, new ContentStreamBase.ByteArrayStream(buf, null), processor);
 [ecj-lint]               } else {
 [ecj-lint]                 throw new RuntimeException("unsupported type ");
 [ecj-lint]               }
 [ecj-lint]             } catch (Exception e) {
 [ecj-lint]               throw new RuntimeException(e);
 [ecj-lint]             } finally {
 [ecj-lint]               params = null;
 [ecj-lint]               req.setParams(old);
 [ecj-lint]             }
 [ecj-lint]           }
 [ecj-lint]         }
 [ecj-lint]         return Collections.emptyList();
 [ecj-lint]       }
 [ecj-lint] 
 [ecj-lint]     }.unmarshal(in);
 [ecj-lint] 	^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 16. INFO in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/logging/log4j2/Log4j2Watcher.java (at line 187)
 [ecj-lint] 	if (logger == root || root.equals(logger) || isRootLogger(name) || "".equals(name)) {
 [ecj-lint] 	                                  ^^^^^^
 [ecj-lint] Unlikely argument type for equals(): Map.Entry<String,LoggerConfig> seems to be unrelated to Logger
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 17. WARNING in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/update/SolrIndexSplitter.java (at line 258)
 [ecj-lint] 	HardlinkCopyDirectoryWrapper hardLinkedDir = new HardlinkCopyDirectoryWrapper(splitDir);
 [ecj-lint] 	                             ^^^^^^^^^^^^^
 [ecj-lint] Resource leak: 'hardLinkedDir' is never closed
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 18. WARNING in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/util/FileUtils.java (at line 50)
 [ecj-lint] 	in = new FileInputStream(src).getChannel();
 [ecj-lint] 	     ^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
 [ecj-lint] ----------
 [ecj-lint] 19. WARNING in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/util/FileUtils.java (at line 51)
 [ecj-lint] 	out = new FileOutputStream(destination).getChannel();
 [ecj-lint] 	      ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 20. WARNING in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/util/SolrCLI.java (at line 1143)
 [ecj-lint] 	SnapshotCloudManager snapshotCloudManager = new SnapshotCloudManager(simCloudManager, config);
 [ecj-lint] 	                     ^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: 'snapshotCloudManager' is never closed
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 21. WARNING in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/util/TestInjection.java (at line 263)
 [ecj-lint] 	timers.remove(this);
 [ecj-lint] 	              ^^^^
 [ecj-lint] Unlikely argument type new TimerTask(){} for remove(Object) on a Collection<Timer>
 [ecj-lint] ----------
 [ecj-lint] 21 problems (8 errors, 12 warnings, 1 info)

BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/build.xml:643: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/build.xml:101: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build.xml:645: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/common-build.xml:2009: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/common-build.xml:2048: Compile failed; see the compiler error output for details.

Total time: 99 minutes 25 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any

Mime
View raw message