You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/07/12 03:04:16 UTC
[JENKINS-EA] Lucene-Solr-master-Windows (64bit/jdk-13-ea+26) -
Build # 8049 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8049/
Java: 64bit/jdk-13-ea+26 -XX:-UseCompressedOops -XX:+UseG1GC
2 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([129EC41BDE9B22E:EFF4F86173584492]: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:567)
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:830)
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">0</int></lst><result name="response" numFound="11" start="0"><doc><str name="id">82</str><str name="test_s_dvo"></str></doc><doc><str name="id">83</str><arr name="test_is_dvo"><int>239207083</int><int>315631185</int><int>335764661</int><int>939932574</int></arr></doc><doc><str name="id">84</str><double name="test_d_dvo">-5.769465809832509E204</double></doc><doc><str name="id">85</str><long name="test_l_dvo">8822458579275712396</long></doc><doc><str name="id">86</str><float name="test_f_dvo">-5.4484336E-33</float></doc><doc><str name="id">87</str><arr name="test_dts_dvo"><date>-7028-06-01T05:06:41.241Z</date><date>-6241-11-06T14:21:14.960Z</date><date>-1906-10-29T18:20:23.452Z</date><date>2037-02-23T08:54:03.998Z</date><date>2427-05-15T03:57:35.386Z</date><date>6585-05-20T19:37:05.952Z</date><date>8235-03-31T14:05:30.813Z</date></arr></doc><doc><str name="id">88</str><str name="test_s_dv">ࠆࠣࠨ࠸ࠔࠍ࠶ࠩࠏ࠲ࠜࠩࠦࠟ</str></doc><doc><str name="id">89</str><str name="test_s_dvo2">䷫䷛䷝䷲䷋䷱䷒䷦䷜䷖䷉䷘</str></doc><doc><str name="id">90</str><arr name="enums_dvo"><str>x6</str><str>x8</str><str>x10</str><str>x17</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
FAILED: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testClassifyStream
Error Message:
expected:<0.0> but was:<0.9998245650830389>
Stack Trace:
java.lang.AssertionError: expected:<0.0> but was:<0.9998245650830389>
at __randomizedtesting.SeedInfo.seed([23BBDFC7F597AAA:A77327C44601633E]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:553)
at org.junit.Assert.assertEquals(Assert.java:683)
at org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testClassifyStream(StreamDecoratorTest.java:3680)
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:567)
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:830)
Build Log:
[...truncated 13771 lines...]
[junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\init-core-data-001
[junit4] 2> 1256714 INFO (SUITE-TestUseDocValuesAsStored-seed#[129EC41BDE9B22E]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1256723 INFO (SUITE-TestUseDocValuesAsStored-seed#[129EC41BDE9B22E]-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> 1256723 INFO (SUITE-TestUseDocValuesAsStored-seed#[129EC41BDE9B22E]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1256726 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testUseDocValuesAsStoredFalse
[junit4] 2> 1256743 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1256750 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1256759 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] 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> 1256763 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
[junit4] 2> 1256770 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
[junit4] 2> 1256771 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\tempDir-001\collection1\conf\managed-schema
[junit4] 2> 1256776 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\tempDir-001\collection1\conf\schema-non-stored-docvalues.xml to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\tempDir-001\collection1\conf\schema-non-stored-docvalues.xml.bak
[junit4] 2> 1256981 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1256983 WARN (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3285bf92[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1256983 WARN (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3285bf92[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1256986 WARN (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@555e5152[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1256986 WARN (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@555e5152[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1257009 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1257009 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1257027 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@867d540
[junit4] 2> 1257033 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@867d540
[junit4] 2> 1257033 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@867d540
[junit4] 2> 1257041 INFO (coreLoadExecutor-5220-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1257049 INFO (coreLoadExecutor-5220-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
[junit4] 2> 1257054 INFO (coreLoadExecutor-5220-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
[junit4] 2> 1257054 INFO (coreLoadExecutor-5220-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\tempDir-001\collection1, trusted=true
[junit4] 2> 1257055 INFO (coreLoadExecutor-5220-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@867d540
[junit4] 2> 1257055 INFO (coreLoadExecutor-5220-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\tempDir-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\init-core-data-001\]
[junit4] 2> 1257059 INFO (coreLoadExecutor-5220-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=39.119140625, floorSegmentMB=1.7509765625, forceMergeDeletesPctAllowed=4.241913668913729, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=30.325865424344283
[junit4] 2> 1257066 WARN (coreLoadExecutor-5220-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> 1257130 INFO (coreLoadExecutor-5220-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1257130 INFO (coreLoadExecutor-5220-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1257132 INFO (coreLoadExecutor-5220-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=12.037109375, floorSegmentMB=0.2724609375, forceMergeDeletesPctAllowed=23.07584550032793, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.623424038227104, deletesPctAllowed=46.59067462987774
[junit4] 2> 1257132 INFO (coreLoadExecutor-5220-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@76f2b6bf[collection1] main]
[junit4] 2> 1257132 INFO (coreLoadExecutor-5220-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\tempDir-001\collection1\conf
[junit4] 2> 1257133 INFO (coreLoadExecutor-5220-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1257134 INFO (searcherExecutor-5221-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@76f2b6bf[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1257134 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1257136 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[myid]} 0 0
[junit4] 2> 1257137 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1257137 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@467658ec commitCommandVersion:0
[junit4] 2> 1257171 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4fe2e44d[collection1] main]
[junit4] 2> 1257171 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1257171 INFO (searcherExecutor-5221-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4fe2e44d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895574006, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1257172 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 38
[junit4] 2> 1257172 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:myid&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1257173 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:myid&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1257173 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:myid&fl=id,nonstored_dv_*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1257173 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:myid&fl=id,nonstored_dv_str&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1257174 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1257174 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] 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> 1257174 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1257174 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2103487407
[junit4] 2> 1257175 INFO (coreCloseExecutor-5225-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@aff6dd3
[junit4] 2> 1257175 INFO (coreCloseExecutor-5225-thread-1) [ x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=aff6dd3
[junit4] 2> 1257175 INFO (coreCloseExecutor-5225-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7ef82fe7: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@1f6c39f2
[junit4] 2> 1257183 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1257184 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5f3755f8: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@37d6a87d
[junit4] 2> 1257188 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1257188 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@58baacaf: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@5197c343
[junit4] 2> 1257190 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1257190 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@408a3dc1: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@58f7e11d
[junit4] 2> 1257192 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[129EC41BDE9B22E]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testUseDocValuesAsStoredFalse
[junit4] 2> 1257195 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testManagedSchema
[junit4] 2> 1257209 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1257215 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1257222 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] 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> 1257223 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
[junit4] 2> 1257230 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
[junit4] 2> 1257230 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\tempDir-002\collection1\conf\managed-schema
[junit4] 2> 1257233 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\tempDir-002\collection1\conf\schema-non-stored-docvalues.xml to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\tempDir-002\collection1\conf\schema-non-stored-docvalues.xml.bak
[junit4] 2> 1257410 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1257412 WARN (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@25e0b2ca[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1257412 WARN (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@25e0b2ca[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1257414 WARN (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6b2b426c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1257414 WARN (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6b2b426c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1257435 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1257435 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1257452 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@867d540
[junit4] 2> 1257458 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@867d540
[junit4] 2> 1257458 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@867d540
[junit4] 2> 1257464 INFO (coreLoadExecutor-5235-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1257471 INFO (coreLoadExecutor-5235-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
[junit4] 2> 1257475 INFO (coreLoadExecutor-5235-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
[junit4] 2> 1257475 INFO (coreLoadExecutor-5235-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\tempDir-002\collection1, trusted=true
[junit4] 2> 1257476 INFO (coreLoadExecutor-5235-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@867d540
[junit4] 2> 1257476 INFO (coreLoadExecutor-5235-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\tempDir-002\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\init-core-data-001\]
[junit4] 2> 1257479 WARN (coreLoadExecutor-5235-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> 1257528 INFO (coreLoadExecutor-5235-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1257528 INFO (coreLoadExecutor-5235-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1257533 INFO (coreLoadExecutor-5235-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=39.119140625, floorSegmentMB=1.7509765625, forceMergeDeletesPctAllowed=4.241913668913729, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=30.325865424344283
[junit4] 2> 1257533 INFO (coreLoadExecutor-5235-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5ab3913b[collection1] main]
[junit4] 2> 1257535 INFO (coreLoadExecutor-5235-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\tempDir-002\collection1\conf
[junit4] 2> 1257535 INFO (coreLoadExecutor-5235-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1257536 INFO (searcherExecutor-5236-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5ab3913b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895574006, os=Windows 10, java.vendor=Oracle Corporation, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1257537 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1257537 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\tempDir-002\collection1\conf\managed-schema
[junit4] 2> 1257542 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\tempDir-002\collection1\conf\managed-schema
[junit4] 2> 1257545 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1257545 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] 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> 1257546 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[myid1]} 0 0
[junit4] 2> 1257546 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1257546 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1d74a6bc commitCommandVersion:0
[junit4] 2> 1257579 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3f5bc9d2[collection1] main]
[junit4] 2> 1257579 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1257582 INFO (searcherExecutor-5236-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3f5bc9d2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895574415, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1257583 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 40
[junit4] 2> 1257587 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:myid*&fl=*&wt=xml} hits=1 status=0 QTime=2
[junit4] 2> 1257587 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1257587 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] 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> 1257587 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1257587 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1445341495
[junit4] 2> 1257588 INFO (coreCloseExecutor-5240-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@e6e5754
[junit4] 2> 1257588 INFO (coreCloseExecutor-5240-thread-1) [ x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=e6e5754
[junit4] 2> 1257588 INFO (coreCloseExecutor-5240-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7a6d9049: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@77a8dc64
[junit4] 2> 1257602 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1257602 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4f38675: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@587ef812
[junit4] 2> 1257606 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1257606 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2717ef95: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@5197c343
[junit4] 2> 1257611 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1257611 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@43258e99: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@58f7e11d
[junit4] 2> 1257615 INFO (TEST-TestUseDocValuesAsStored.testManagedSchema-seed#[129EC41BDE9B22E]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testManagedSchema
[junit4] 2> 1257618 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued
[junit4] 2> 1257629 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1257636 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1257642 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] 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> 1257645 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
[junit4] 2> 1257650 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
[junit4] 2> 1257652 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\tempDir-003\collection1\conf\managed-schema
[junit4] 2> 1257655 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\tempDir-003\collection1\conf\schema-non-stored-docvalues.xml to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\tempDir-003\collection1\conf\schema-non-stored-docvalues.xml.bak
[junit4] 2> 1257844 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1257845 WARN (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4889e0e9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1257846 WARN (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4889e0e9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1257849 WARN (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7f17ebb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1257849 WARN (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7f17ebb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1257878 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1257878 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1257902 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@867d540
[junit4] 2> 1257911 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@867d540
[junit4] 2> 1257913 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@867d540
[junit4] 2> 1257924 INFO (coreLoadExecutor-5250-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1257935 INFO (coreLoadExecutor-5250-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
[junit4] 2> 1257942 INFO (coreLoadExecutor-5250-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
[junit4] 2> 1257942 INFO (coreLoadExecutor-5250-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\tempDir-003\collection1, trusted=true
[junit4] 2> 1257942 INFO (coreLoadExecutor-5250-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@867d540
[junit4] 2> 1257942 INFO (coreLoadExecutor-5250-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\tempDir-003\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\init-core-data-001\]
[junit4] 2> 1257949 WARN (coreLoadExecutor-5250-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> 1258040 INFO (coreLoadExecutor-5250-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1258040 INFO (coreLoadExecutor-5250-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1258042 INFO (coreLoadExecutor-5250-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=39.119140625, floorSegmentMB=1.7509765625, forceMergeDeletesPctAllowed=4.241913668913729, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=30.325865424344283
[junit4] 2> 1258044 INFO (coreLoadExecutor-5250-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@463fe626[collection1] main]
[junit4] 2> 1258046 INFO (coreLoadExecutor-5250-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored_129EC41BDE9B22E-001\tempDir-003\collection1\conf
[junit4] 2> 1258047 INFO (coreLoadExecutor-5250-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1258047 INFO (searcherExecutor-5251-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@463fe626[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895574415, os=Windows 10, java.vendor=Oracle Corporation, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1258049 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1258050 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1258050 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] 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> 1258052 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 1258052 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1258052 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4b96a12c commitCommandVersion:0
[junit4] 2> 1258099 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@29e1105c[collection1] main]
[junit4] 2> 1258101 INFO (searcherExecutor-5251-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@29e1105c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895574924, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1258101 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1258101 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 54
[junit4] 2> 1258102 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] 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> 1258103 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] 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> 1258113 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] 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> 1258115 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] 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> 1258117 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] 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> 1258118 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258120 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 1258121 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1258121 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4b96a12c commitCommandVersion:0
[junit4] 2> 1258151 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@44c44243[collection1] main]
[junit4] 2> 1258151 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1258152 INFO (searcherExecutor-5251-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@44c44243[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895574924, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895574979, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1258153 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 35
[junit4] 2> 1258153 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&fl=test_is_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258154 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&fl=test_is_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258154 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] 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> 1258154 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] 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> 1258157 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&fl=test_is_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258158 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 1258160 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 1258160 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1258160 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4b96a12c commitCommandVersion:0
[junit4] 2> 1258180 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6b28236[collection1] main]
[junit4] 2> 1258181 INFO (searcherExecutor-5251-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6b28236[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895574924, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895574979, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575017, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1258181 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1258181 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 25
[junit4] 2> 1258181 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:3&fl=test_d_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258185 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:3&fl=test_d_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258185 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] 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> 1258186 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] 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> 1258186 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:3&fl=test_d_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=1
[junit4] 2> 1258186 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
[junit4] 2> 1258189 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 1258189 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1258189 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4b96a12c commitCommandVersion:0
[junit4] 2> 1258210 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@10a70[collection1] main]
[junit4] 2> 1258210 INFO (searcherExecutor-5251-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@10a70[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895574924, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895574979, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575017, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575047, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1258210 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1258210 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 24
[junit4] 2> 1258231 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&fl=test_ls_dvo&wt=xml} hits=1 status=0 QTime=21
[junit4] 2> 1258233 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&fl=test_ls_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258233 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] 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> 1258235 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&fl=*&wt=xml} hits=1 status=0 QTime=1
[junit4] 2> 1258236 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&fl=test_ls_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258237 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
[junit4] 2> 1258239 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 1258239 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1258239 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4b96a12c commitCommandVersion:0
[junit4] 2> 1258269 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@72680bf8[collection1] main]
[junit4] 2> 1258269 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1258269 INFO (searcherExecutor-5251-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@72680bf8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895574924, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895574979, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575017, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575047, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575097, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1258269 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 35
[junit4] 2> 1258272 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&fl=test_f_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258273 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&fl=test_f_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258273 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] 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> 1258273 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] 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> 1258274 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&fl=test_f_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258274 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
[junit4] 2> 1258298 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 1258298 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1258298 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4b96a12c commitCommandVersion:0
[junit4] 2> 1258364 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@c933d5c[collection1] main]
[junit4] 2> 1258365 INFO (searcherExecutor-5251-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@c933d5c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895574924, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895574979, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575017, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575047, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575097, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575201, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1258366 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1258366 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 74
[junit4] 2> 1258366 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:6&fl=test_dts_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258366 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:6&fl=test_dts_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258428 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:6&fl=*dvo&wt=xml} hits=1 status=0 QTime=67
[junit4] 2> 1258466 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:6&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258468 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:6&fl=test_dts_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258468 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
[junit4] 2> 1258471 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 1258471 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1258471 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4b96a12c commitCommandVersion:0
[junit4] 2> 1258495 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@14e998fe[collection1] main]
[junit4] 2> 1258495 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1258495 INFO (searcherExecutor-5251-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@14e998fe[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895574924, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895574979, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575017, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575047, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575097, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575201, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_8(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575328, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1258497 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 28
[junit4] 2> 1258497 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:7&fl=test_ss_dv&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258497 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:7&fl=test_ss_dv,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258499 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:7&fl=*_dv&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258501 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:7&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258502 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:7&fl=test_ss_dv,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258503 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
[junit4] 2> 1258505 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 1258505 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1258505 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4b96a12c commitCommandVersion:0
[junit4] 2> 1258533 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a166dae[collection1] main]
[junit4] 2> 1258533 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1258534 INFO (searcherExecutor-5251-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1a166dae[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895574924, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895574979, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575017, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575047, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575097, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575201, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_8(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575328, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_9(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575370, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1258535 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 32
[junit4] 2> 1258535 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&fl=test_ss_dvo2&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258535 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&fl=test_ss_dvo2,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258536 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&fl=*vo2&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258537 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258538 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&fl=test_ss_dvo2,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1258539 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
[junit4] 2> 1258542 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 1
[junit4] 2> 1258542 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1258542 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4b96a12c commitCommandVersion:0
[junit4] 2> 1258566 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[129EC41BDE9B22E]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@cc540e8[collection1] main]
[junit4] 2> 1258567 INFO (searcherExecutor-5251-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@cc540e8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895574924, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895574979, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575017, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575047, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575097, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575201, java.vendor=Oracle Corporation, os=Windows 10, os.version=10.0, java.runtime.version=13-ea+26, os.arch=amd64, source=flush, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_8(9.0.0):c1:[diagnostics={java.vm.version=13-ea+26, java.version=13-ea, timestamp=1562895575328, java.vendor=Ora
[...truncated too long message...]
llection/terms/shard1
[junit4] 2> 3 /solr/collections/mainCorpus/terms/shard2
[junit4] 2> 3 /solr/collections/collection1/terms/shard1
[junit4] 2> 3 /solr/collections/collection1/terms/shard2
[junit4] 2> 3 /solr/collections/uknownCollection/terms/shard2
[junit4] 2> 3 /solr/collections/uknownCollection/terms/shard1
[junit4] 2> 3 /solr/collections/workQueue/terms/shard2
[junit4] 2> 2 /solr/collections/collection1/collectionprops.json
[junit4] 2> 2 /solr/collections/mainCorpus1/collectionprops.json
[junit4] 2> 2 /solr/collections/destinationCollection/collectionprops.json
[junit4] 2> 2 /solr/collections/workQueue/collectionprops.json
[junit4] 2> 2 /solr/collections/destination/collectionprops.json
[junit4] 2> 2 /solr/collections/destination1/collectionprops.json
[junit4] 2> 2 /solr/collections/workQueue1/collectionprops.json
[junit4] 2> 2 /solr/collections/checkpointCollection/terms/shard2
[junit4] 2> 2 /solr/collections/modelCollection/collectionprops.json
[junit4] 2> 2 /solr/collections/uknownCollection/collectionprops.json
[junit4] 2> 2 /solr/collections/mainCorpus/collectionprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 75 /solr/collections/parallelDestinationCollection1/state.json
[junit4] 2> 72 /solr/clusterstate.json
[junit4] 2> 43 /solr/collections/parallelDestinationCollection/state.json
[junit4] 2> 38 /solr/collections/destinationCollection/state.json
[junit4] 2> 27 /solr/collections/workQueue/state.json
[junit4] 2> 27 /solr/collections/modelCollection/state.json
[junit4] 2> 26 /solr/collections/collection1/state.json
[junit4] 2> 25 /solr/collections/uknownCollection/state.json
[junit4] 2> 25 /solr/collections/checkpointCollection/state.json
[junit4] 2> 20 /solr/collections/destination1/state.json
[junit4] 2> 20 /solr/collections/mainCorpus/state.json
[junit4] 2> 19 /solr/collections/mainCorpus1/state.json
[junit4] 2> 19 /solr/collections/destination/state.json
[junit4] 2> 19 /solr/collections/workQueue1/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 361 /solr/collections
[junit4] 2> 247 /solr/overseer/queue
[junit4] 2> 108 /solr/live_nodes
[junit4] 2> 90 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 462297 INFO (SUITE-StreamDecoratorTest-seed#[23BBDFC7F597AAA]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:50593
[junit4] 2> 462297 INFO (SUITE-StreamDecoratorTest-seed#[23BBDFC7F597AAA]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 50593
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.io.stream.StreamDecoratorTest_23BBDFC7F597AAA-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {expr_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), name_s=Lucene50(blocksize=128), terms_ss=PostingsFormat(name=Asserting), join2_s=PostingsFormat(name=Asserting), field_s=PostingsFormat(name=LuceneVarGapFixedInterval), multiDefault=PostingsFormat(name=Asserting), subject=PostingsFormat(name=Asserting), a1_s=PostingsFormat(name=Asserting), a2_s=Lucene50(blocksize=128), body_t=Lucene50(blocksize=128), s_multi=Lucene50(blocksize=128), a_ss=PostingsFormat(name=LuceneVarGapFixedInterval), a_s=Lucene50(blocksize=128), tv_text=PostingsFormat(name=LuceneVarGapFixedInterval), text_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), text=Lucene50(blocksize=128), bbid_s=Lucene50(blocksize=128), type_s=PostingsFormat(name=LuceneVarGapFixedInterval), checkpoint_ss=Lucene50(blocksize=128), test_t=Lucene50(blocksize=128), ident_s=PostingsFormat(name=Asserting), extra_s=PostingsFormat(name=Asserting), ykey_s=PostingsFormat(name=LuceneVarGapFixedInterval), side_s=Lucene50(blocksize=128)}, docValues:{expr_s=DocValuesFormat(name=Lucene80), name_s=DocValuesFormat(name=Asserting), iteration_i=DocValuesFormat(name=Lucene80), terms_ss=DocValuesFormat(name=Direct), join2_s=DocValuesFormat(name=Direct), field_s=DocValuesFormat(name=Lucene80), multiDefault=DocValuesFormat(name=Direct), a1_s=DocValuesFormat(name=Direct), a_f=DocValuesFormat(name=Direct), intDefault=DocValuesFormat(name=Lucene80), a2_s=DocValuesFormat(name=Asserting), join1_i=DocValuesFormat(name=Lucene80), falseNegative_i=DocValuesFormat(name=Lucene80), a_i=DocValuesFormat(name=Lucene80), test_i=DocValuesFormat(name=Direct), join3_i=DocValuesFormat(name=Asserting), falsePositive_i=DocValuesFormat(name=Lucene80), field_i=DocValuesFormat(name=Direct), s_multi=DocValuesFormat(name=Asserting), a_ss=DocValuesFormat(name=Lucene80), a_s=DocValuesFormat(name=Asserting), text_s=DocValuesFormat(name=Lucene80), id=DocValuesFormat(name=Lucene80), bbid_s=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Lucene80), idfs_ds=DocValuesFormat(name=Lucene80), type_s=DocValuesFormat(name=Lucene80), alpha_d=DocValuesFormat(name=Asserting), i_multi=DocValuesFormat(name=Lucene80), weights_ds=DocValuesFormat(name=Asserting), trueNegative_i=DocValuesFormat(name=Asserting), b_ls=DocValuesFormat(name=Direct), out_i=DocValuesFormat(name=Direct), checkpoint_ss=DocValuesFormat(name=Asserting), ident_s=DocValuesFormat(name=Direct), _version_=DocValuesFormat(name=Lucene80), error_d=DocValuesFormat(name=Lucene80), extra_s=DocValuesFormat(name=Direct), ykey_s=DocValuesFormat(name=Lucene80), side_s=DocValuesFormat(name=Asserting), truePositive_i=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1045, maxMBSortInHeap=7.443360791832037, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@bb9ac88), locale=lu, timezone=Asia/Qyzylorda
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 13-ea (64-bit)/cpus=3,threads=1,free=267555456,total=455081984
[junit4] 2> NOTE: All tests run in this JVM: [FieldValueEvaluatorTest, TestFastWriter, ModuloEvaluatorTest, SquareRootEvaluatorTest, TestFastInputStream, OrEvaluatorTest, SolrExampleBinaryTest, TangentEvaluatorTest, SelectWithEvaluatorsTest, TestPolicy2Old, ArcCosineEvaluatorTest, SolrSchemalessExampleTest, TestCloudCollectionsListeners, ClientUtilsTest, TestLBHttp2SolrClient, GetByIdTest, TestV2Request, SolrParamTest, NaturalLogEvaluatorTest, TestClusteringResponse, TestSpellCheckResponse, ModifiableSolrParamsTest, TestUpdateRequestCodec, Http2SolrClientTest, JettyWebappTest, ConcurrentUpdateHttp2SolrClientBadInputTest, CorrelationEvaluatorTest, JsonValidatorTest, SchemaTest, NormalizeEvaluatorTest, TestCoreAdmin, TestEmbeddedSolrServer, CloudSolrClientCacheTest, AndEvaluatorTest, LBHttpSolrClientBadInputTest, QueryFacetMapTest, JdbcDriverTest, SolrExceptionTest, SolrExampleStreamingBinaryTest, TestNamedListCodec, MergeIndexesEmbeddedTest, DomainMapTest, StreamExpressionTest, CloudSolrClientRetryTest, RoundEvaluatorTest, CloudSolrClientBadInputTest, CloudHttp2SolrClientBuilderTest, StreamExpressionToExplanationTest, CubedRootEvaluatorTest, JsonRequestApiHeatmapFacetingTest, JsonRequestApiTest, UsingSolrJRefGuideExamplesTest, ZkConfigFilesTest, SolrExampleBinaryHttp2Test, SolrExampleXMLTest, SolrQueryTest, TestSolrJErrorHandling, TestPolicy, TestPolicy2, TestPolicyOld, LargeVolumeBinaryJettyTest, LargeVolumeEmbeddedTest, LargeVolumeJettyTest, SolrExampleEmbeddedTest, SolrExampleJettyTest, SolrExampleStreamingBinaryHttp2Test, SolrExampleStreamingTest, SolrExampleXMLHttp2Test, BasicHttpSolrClientTest, CloudHttp2SolrClientMultiConstructorTest, CloudHttp2SolrClientRetryTest, CloudSolrClientBuilderTest, ConcurrentUpdateHttp2SolrClientTest, ConcurrentUpdateSolrClientBadInputTest, ConcurrentUpdateSolrClientBuilderTest, ConcurrentUpdateSolrClientTest, Http2SolrClientCompatibilityTest, HttpSolrClientBadInputTest, HttpSolrClientBuilderTest, HttpSolrClientConPoolTest, LBHttpSolrClientBuilderTest, TestCloudSolrClientConnections, TestLang, GraphExpressionTest, JDBCStreamTest, StreamDecoratorTest]
[junit4] Completed [166/199 (1!)] on J1 in 88.18s, 49 tests, 1 failure <<< FAILURES!
[...truncated 43894 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8049/consoleText
[repro] Revision: f85a78c44185c6cb620866a1ab37510757392ed0
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC"
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] TestUseDocValuesAsStored
[repro] solr\solrj
[repro] StreamDecoratorTest
[repro] ant compile-test
[...truncated 3358 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestUseDocValuesAsStored" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC" -Dtests.seed=129EC41BDE9B22E -Dtests.slow=true -Dtests.locale=ln-AO -Dtests.timezone=Etc/GMT0 -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[...truncated 103 lines...]
[repro] ant compile-test
[...truncated 454 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.StreamDecoratorTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC" -Dtests.seed=23BBDFC7F597AAA -Dtests.slow=true -Dtests.locale=lu -Dtests.timezone=Asia/Qyzylorda -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[...truncated 313 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest
[repro] 0/5 failed: org.apache.solr.schema.TestUseDocValuesAsStored
[repro] Exiting with code 0
[...truncated 78 lines...]
[JENKINS] Lucene-Solr-master-Windows (64bit/jdk-12.0.1) - Build #
8050 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8050/
Java: 64bit/jdk-12.0.1 -XX:+UseCompressedOops -XX:+UseSerialGC
5 tests failed.
FAILED: org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth
Error Message:
Software caused connection abort: recv failed
Stack Trace:
javax.net.ssl.SSLException: Software caused connection abort: recv failed
at __randomizedtesting.SeedInfo.seed([438E29C06A18E64D:FFE05FD2CE4B6537]:0)
at java.base/sun.security.ssl.Alert.createSSLException(Alert.java:127)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:320)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:263)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:258)
at java.base/sun.security.ssl.SSLSocketImpl.handleException(SSLSocketImpl.java:1342)
at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:844)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:108)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.cloud.SolrCloudAuthTestCase.verifySecurityStatus(SolrCloudAuthTestCase.java:200)
at org.apache.solr.cloud.SolrCloudAuthTestCase.verifySecurityStatus(SolrCloudAuthTestCase.java:176)
at org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth(BasicAuthIntegrationTest.java:127)
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:567)
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:835)
Suppressed: java.net.SocketException: Software caused connection abort: socket write error
at java.base/java.net.SocketOutputStream.socketWrite0(Native Method)
at java.base/java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:110)
at java.base/java.net.SocketOutputStream.write(SocketOutputStream.java:150)
at java.base/sun.security.ssl.SSLSocketOutputRecord.encodeAlert(SSLSocketOutputRecord.java:81)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:351)
... 63 more
Caused by: java.net.SocketException: Software caused connection abort: recv failed
at java.base/java.net.SocketInputStream.socketRead0(Native Method)
at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:448)
at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68)
at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1132)
at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:828)
... 59 more
FAILED: org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth
Error Message:
Software caused connection abort: recv failed
Stack Trace:
javax.net.ssl.SSLException: Software caused connection abort: recv failed
at __randomizedtesting.SeedInfo.seed([438E29C06A18E64D:FFE05FD2CE4B6537]:0)
at java.base/sun.security.ssl.Alert.createSSLException(Alert.java:127)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:320)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:263)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:258)
at java.base/sun.security.ssl.SSLSocketImpl.handleException(SSLSocketImpl.java:1342)
at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:844)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:108)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.cloud.SolrCloudAuthTestCase.verifySecurityStatus(SolrCloudAuthTestCase.java:200)
at org.apache.solr.cloud.SolrCloudAuthTestCase.verifySecurityStatus(SolrCloudAuthTestCase.java:176)
at org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth(BasicAuthIntegrationTest.java:127)
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:567)
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:835)
Suppressed: java.net.SocketException: Software caused connection abort: socket write error
at java.base/java.net.SocketOutputStream.socketWrite0(Native Method)
at java.base/java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:110)
at java.base/java.net.SocketOutputStream.write(SocketOutputStream.java:150)
at java.base/sun.security.ssl.SSLSocketOutputRecord.encodeAlert(SSLSocketOutputRecord.java:81)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:351)
... 63 more
Caused by: java.net.SocketException: Software caused connection abort: recv failed
at java.base/java.net.SocketInputStream.socketRead0(Native Method)
at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:448)
at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68)
at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1132)
at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:828)
... 59 more
FAILED: org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth
Error Message:
Software caused connection abort: recv failed
Stack Trace:
javax.net.ssl.SSLException: Software caused connection abort: recv failed
at __randomizedtesting.SeedInfo.seed([438E29C06A18E64D:FFE05FD2CE4B6537]:0)
at java.base/sun.security.ssl.Alert.createSSLException(Alert.java:127)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:320)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:263)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:258)
at java.base/sun.security.ssl.SSLSocketImpl.handleException(SSLSocketImpl.java:1342)
at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:844)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:108)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.cloud.SolrCloudAuthTestCase.verifySecurityStatus(SolrCloudAuthTestCase.java:200)
at org.apache.solr.cloud.SolrCloudAuthTestCase.verifySecurityStatus(SolrCloudAuthTestCase.java:176)
at org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth(BasicAuthIntegrationTest.java:127)
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:567)
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:835)
Suppressed: java.net.SocketException: Software caused connection abort: socket write error
at java.base/java.net.SocketOutputStream.socketWrite0(Native Method)
at java.base/java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:110)
at java.base/java.net.SocketOutputStream.write(SocketOutputStream.java:150)
at java.base/sun.security.ssl.SSLSocketOutputRecord.encodeAlert(SSLSocketOutputRecord.java:81)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:351)
... 63 more
Caused by: java.net.SocketException: Software caused connection abort: recv failed
at java.base/java.net.SocketInputStream.socketRead0(Native Method)
at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:448)
at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68)
at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1132)
at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:828)
... 59 more
FAILED: org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth
Error Message:
Software caused connection abort: recv failed
Stack Trace:
javax.net.ssl.SSLException: Software caused connection abort: recv failed
at __randomizedtesting.SeedInfo.seed([438E29C06A18E64D:FFE05FD2CE4B6537]:0)
at java.base/sun.security.ssl.Alert.createSSLException(Alert.java:127)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:320)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:263)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:258)
at java.base/sun.security.ssl.SSLSocketImpl.handleException(SSLSocketImpl.java:1342)
at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:844)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:108)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.cloud.SolrCloudAuthTestCase.verifySecurityStatus(SolrCloudAuthTestCase.java:200)
at org.apache.solr.cloud.SolrCloudAuthTestCase.verifySecurityStatus(SolrCloudAuthTestCase.java:176)
at org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth(BasicAuthIntegrationTest.java:127)
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:567)
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:835)
Suppressed: java.net.SocketException: Software caused connection abort: socket write error
at java.base/java.net.SocketOutputStream.socketWrite0(Native Method)
at java.base/java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:110)
at java.base/java.net.SocketOutputStream.write(SocketOutputStream.java:150)
at java.base/sun.security.ssl.SSLSocketOutputRecord.encodeAlert(SSLSocketOutputRecord.java:81)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:351)
... 63 more
Caused by: java.net.SocketException: Software caused connection abort: recv failed
at java.base/java.net.SocketInputStream.socketRead0(Native Method)
at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:448)
at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68)
at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1132)
at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:828)
... 59 more
FAILED: org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth
Error Message:
Software caused connection abort: recv failed
Stack Trace:
javax.net.ssl.SSLException: Software caused connection abort: recv failed
at __randomizedtesting.SeedInfo.seed([438E29C06A18E64D:FFE05FD2CE4B6537]:0)
at java.base/sun.security.ssl.Alert.createSSLException(Alert.java:127)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:320)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:263)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:258)
at java.base/sun.security.ssl.SSLSocketImpl.handleException(SSLSocketImpl.java:1342)
at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:844)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:108)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.cloud.SolrCloudAuthTestCase.verifySecurityStatus(SolrCloudAuthTestCase.java:200)
at org.apache.solr.cloud.SolrCloudAuthTestCase.verifySecurityStatus(SolrCloudAuthTestCase.java:176)
at org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth(BasicAuthIntegrationTest.java:127)
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:567)
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:835)
Suppressed: java.net.SocketException: Software caused connection abort: socket write error
at java.base/java.net.SocketOutputStream.socketWrite0(Native Method)
at java.base/java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:110)
at java.base/java.net.SocketOutputStream.write(SocketOutputStream.java:150)
at java.base/sun.security.ssl.SSLSocketOutputRecord.encodeAlert(SSLSocketOutputRecord.java:81)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:351)
... 63 more
Caused by: java.net.SocketException: Software caused connection abort: recv failed
at java.base/java.net.SocketInputStream.socketRead0(Native Method)
at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:448)
at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68)
at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1132)
at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:828)
... 59 more
Build Log:
[...truncated 13430 lines...]
[junit4] Suite: org.apache.solr.security.BasicAuthIntegrationTest
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_438E29C06A18E64D-001\init-core-data-001
[junit4] 2> 1332828 WARN (SUITE-BasicAuthIntegrationTest-seed#[438E29C06A18E64D]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=5 numCloses=5
[junit4] 2> 1332828 INFO (SUITE-BasicAuthIntegrationTest-seed#[438E29C06A18E64D]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1332830 INFO (SUITE-BasicAuthIntegrationTest-seed#[438E29C06A18E64D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1332830 INFO (SUITE-BasicAuthIntegrationTest-seed#[438E29C06A18E64D]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1332839 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[438E29C06A18E64D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBasicAuth
[junit4] 2> 1332840 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[438E29C06A18E64D]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_438E29C06A18E64D-001\tempDir-001
[junit4] 2> 1332841 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[438E29C06A18E64D]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1332841 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1332841 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1332943 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[438E29C06A18E64D]) [ ] o.a.s.c.ZkTestServer start zk server on port:61726
[junit4] 2> 1332943 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[438E29C06A18E64D]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:61726
[junit4] 2> 1332943 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[438E29C06A18E64D]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 61726
[junit4] 2> 1332954 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[438E29C06A18E64D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1332969 INFO (zkConnectionManagerCallback-5448-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1332969 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[438E29C06A18E64D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1333004 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[438E29C06A18E64D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1333008 INFO (zkConnectionManagerCallback-5450-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1333008 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[438E29C06A18E64D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1333011 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[438E29C06A18E64D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1333036 INFO (zkConnectionManagerCallback-5452-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1333036 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[438E29C06A18E64D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1333173 WARN (jetty-launcher-5453-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1333173 INFO (jetty-launcher-5453-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1333173 INFO (jetty-launcher-5453-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1333173 INFO (jetty-launcher-5453-thread-2) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 1333173 WARN (jetty-launcher-5453-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1333173 INFO (jetty-launcher-5453-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1333173 INFO (jetty-launcher-5453-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1333173 INFO (jetty-launcher-5453-thread-3) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 1333175 INFO (jetty-launcher-5453-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1333175 INFO (jetty-launcher-5453-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1333175 INFO (jetty-launcher-5453-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1333175 INFO (jetty-launcher-5453-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3c355158{/solr,null,AVAILABLE}
[junit4] 2> 1333176 WARN (jetty-launcher-5453-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1333176 INFO (jetty-launcher-5453-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1333176 INFO (jetty-launcher-5453-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1333176 INFO (jetty-launcher-5453-thread-1) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 1333177 INFO (jetty-launcher-5453-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1333177 INFO (jetty-launcher-5453-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1333177 INFO (jetty-launcher-5453-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1333177 INFO (jetty-launcher-5453-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@437853d8{/solr,null,AVAILABLE}
[junit4] 2> 1333177 INFO (jetty-launcher-5453-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1333177 INFO (jetty-launcher-5453-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1333177 INFO (jetty-launcher-5453-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1333177 INFO (jetty-launcher-5453-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5efc0ced{/solr,null,AVAILABLE}
[junit4] 2> 1333186 INFO (jetty-launcher-5453-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@108eb22{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:61743}
[junit4] 2> 1333186 INFO (jetty-launcher-5453-thread-2) [ ] o.e.j.s.Server Started @1336727ms
[junit4] 2> 1333186 INFO (jetty-launcher-5453-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61743}
[junit4] 2> 1333186 ERROR (jetty-launcher-5453-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1333186 INFO (jetty-launcher-5453-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1333187 INFO (jetty-launcher-5453-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1333187 INFO (jetty-launcher-5453-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1333187 INFO (jetty-launcher-5453-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1333187 INFO (jetty-launcher-5453-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-07-12T16:13:13.244558800Z
[junit4] 2> 1333188 INFO (jetty-launcher-5453-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3d56536c{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:61739}
[junit4] 2> 1333188 INFO (jetty-launcher-5453-thread-3) [ ] o.e.j.s.Server Started @1336729ms
[junit4] 2> 1333188 INFO (jetty-launcher-5453-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1333188 INFO (jetty-launcher-5453-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61739}
[junit4] 2> 1333192 INFO (jetty-launcher-5453-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@29353eb1{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:61740}
[junit4] 2> 1333192 INFO (jetty-launcher-5453-thread-1) [ ] o.e.j.s.Server Started @1336733ms
[junit4] 2> 1333192 INFO (jetty-launcher-5453-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61740}
[junit4] 2> 1333192 ERROR (jetty-launcher-5453-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1333192 INFO (jetty-launcher-5453-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1333192 INFO (jetty-launcher-5453-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1333192 INFO (jetty-launcher-5453-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1333192 INFO (jetty-launcher-5453-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1333192 INFO (jetty-launcher-5453-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-07-12T16:13:13.249828100Z
[junit4] 2> 1333192 ERROR (jetty-launcher-5453-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1333192 INFO (jetty-launcher-5453-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1333193 INFO (jetty-launcher-5453-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1333193 INFO (jetty-launcher-5453-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1333193 INFO (jetty-launcher-5453-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1333193 INFO (jetty-launcher-5453-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-07-12T16:13:13.250636100Z
[junit4] 2> 1333194 INFO (jetty-launcher-5453-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1333194 INFO (jetty-launcher-5453-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1333202 INFO (zkConnectionManagerCallback-5457-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1333202 INFO (jetty-launcher-5453-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1333203 INFO (zkConnectionManagerCallback-5455-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1333203 INFO (jetty-launcher-5453-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1333203 INFO (jetty-launcher-5453-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1333204 INFO (jetty-launcher-5453-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1333213 INFO (zkConnectionManagerCallback-5459-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1333213 INFO (jetty-launcher-5453-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1333333 INFO (jetty-launcher-5453-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1333774 INFO (jetty-launcher-5453-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1333832 WARN (jetty-launcher-5453-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@38ab4f89[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1333836 WARN (jetty-launcher-5453-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@38ab4f89[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1333844 WARN (jetty-launcher-5453-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2d8d4f28[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1333844 WARN (jetty-launcher-5453-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2d8d4f28[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1333844 INFO (jetty-launcher-5453-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61726/solr
[junit4] 2> 1333847 INFO (jetty-launcher-5453-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1333853 INFO (zkConnectionManagerCallback-5468-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1333853 INFO (jetty-launcher-5453-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1333901 INFO (jetty-launcher-5453-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1333911 WARN (jetty-launcher-5453-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1ad993c2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1333911 WARN (jetty-launcher-5453-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1ad993c2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1333923 WARN (jetty-launcher-5453-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6273c350[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1333923 WARN (jetty-launcher-5453-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6273c350[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1333923 INFO (jetty-launcher-5453-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61726/solr
[junit4] 2> 1333932 INFO (jetty-launcher-5453-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1333948 INFO (zkConnectionManagerCallback-5474-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1333948 INFO (jetty-launcher-5453-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1333961 INFO (jetty-launcher-5453-thread-2) [n:127.0.0.1:61743_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1333975 INFO (zkConnectionManagerCallback-5476-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1333975 INFO (jetty-launcher-5453-thread-2) [n:127.0.0.1:61743_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1333981 INFO (jetty-launcher-5453-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1333997 WARN (jetty-launcher-5453-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@79d0346c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1333997 WARN (jetty-launcher-5453-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@79d0346c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1334008 WARN (jetty-launcher-5453-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2d42dff9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1334008 WARN (jetty-launcher-5453-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2d42dff9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1334010 INFO (jetty-launcher-5453-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61726/solr
[junit4] 2> 1334010 INFO (jetty-launcher-5453-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1334024 INFO (zkConnectionManagerCallback-5484-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1334024 INFO (jetty-launcher-5453-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1334071 INFO (jetty-launcher-5453-thread-1) [n:127.0.0.1:61740_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1334111 INFO (zkConnectionManagerCallback-5486-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1334111 INFO (jetty-launcher-5453-thread-1) [n:127.0.0.1:61740_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1334131 INFO (jetty-launcher-5453-thread-1) [n:127.0.0.1:61740_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:61740_solr
[junit4] 2> 1334132 INFO (jetty-launcher-5453-thread-1) [n:127.0.0.1:61740_solr ] o.a.s.c.Overseer Overseer (id=72068703706349578-127.0.0.1:61740_solr-n_0000000000) starting
[junit4] 2> 1334141 INFO (jetty-launcher-5453-thread-1) [n:127.0.0.1:61740_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1334145 INFO (zkConnectionManagerCallback-5493-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1334145 INFO (jetty-launcher-5453-thread-1) [n:127.0.0.1:61740_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1334149 INFO (jetty-launcher-5453-thread-1) [n:127.0.0.1:61740_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:61726/solr ready
[junit4] 2> 1334151 INFO (jetty-launcher-5453-thread-1) [n:127.0.0.1:61740_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61740_solr
[junit4] 2> 1334183 INFO (jetty-launcher-5453-thread-2) [n:127.0.0.1:61743_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1334189 INFO (jetty-launcher-5453-thread-2) [n:127.0.0.1:61743_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:61743_solr as DOWN
[junit4] 2> 1334189 INFO (jetty-launcher-5453-thread-2) [n:127.0.0.1:61743_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1334189 INFO (jetty-launcher-5453-thread-2) [n:127.0.0.1:61743_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61743_solr
[junit4] 2> 1334193 INFO (jetty-launcher-5453-thread-2) [n:127.0.0.1:61743_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1334197 INFO (jetty-launcher-5453-thread-3) [n:127.0.0.1:61739_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1334202 INFO (OverseerStateUpdate-72068703706349578-127.0.0.1:61740_solr-n_0000000000) [n:127.0.0.1:61740_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:61740_solr
[junit4] 2> 1334204 INFO (OverseerStateUpdate-72068703706349578-127.0.0.1:61740_solr-n_0000000000) [n:127.0.0.1:61740_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1334205 INFO (jetty-launcher-5453-thread-1) [n:127.0.0.1:61740_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1334251 INFO (jetty-launcher-5453-thread-1) [n:127.0.0.1:61740_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61740.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6b68e51
[junit4] 2> 1334265 INFO (jetty-launcher-5453-thread-1) [n:127.0.0.1:61740_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61740.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6b68e51
[junit4] 2> 1334265 INFO (jetty-launcher-5453-thread-1) [n:127.0.0.1:61740_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61740.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6b68e51
[junit4] 2> 1334265 INFO (jetty-launcher-5453-thread-1) [n:127.0.0.1:61740_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_438E29C06A18E64D-001\tempDir-001\node1\.
[junit4] 2> 1334265 INFO (zkCallback-5492-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1334265 INFO (zkCallback-5475-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1334293 INFO (zkConnectionManagerCallback-5498-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1334293 INFO (jetty-launcher-5453-thread-2) [n:127.0.0.1:61743_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1334295 INFO (jetty-launcher-5453-thread-2) [n:127.0.0.1:61743_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1334296 INFO (zkConnectionManagerCallback-5500-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1334296 INFO (jetty-launcher-5453-thread-2) [n:127.0.0.1:61743_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:61726/solr ready
[junit4] 2> 1334296 INFO (jetty-launcher-5453-thread-3) [n:127.0.0.1:61739_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1334305 INFO (jetty-launcher-5453-thread-3) [n:127.0.0.1:61739_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1334311 INFO (jetty-launcher-5453-thread-3) [n:127.0.0.1:61739_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:61739_solr as DOWN
[junit4] 2> 1334311 INFO (jetty-launcher-5453-thread-3) [n:127.0.0.1:61739_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1334311 INFO (jetty-launcher-5453-thread-3) [n:127.0.0.1:61739_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61739_solr
[junit4] 2> 1334317 INFO (zkCallback-5485-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1334317 INFO (zkCallback-5475-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1334317 INFO (zkCallback-5492-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1334319 INFO (jetty-launcher-5453-thread-3) [n:127.0.0.1:61739_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1334319 INFO (zkCallback-5499-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1334321 INFO (zkCallback-5497-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1334323 INFO (zkConnectionManagerCallback-5508-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1334323 INFO (jetty-launcher-5453-thread-3) [n:127.0.0.1:61739_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1334327 INFO (jetty-launcher-5453-thread-3) [n:127.0.0.1:61739_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1334328 INFO (jetty-launcher-5453-thread-3) [n:127.0.0.1:61739_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:61726/solr ready
[junit4] 2> 1334343 INFO (jetty-launcher-5453-thread-2) [n:127.0.0.1:61743_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1334375 INFO (jetty-launcher-5453-thread-2) [n:127.0.0.1:61743_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61743.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6b68e51
[junit4] 2> 1334377 INFO (jetty-launcher-5453-thread-3) [n:127.0.0.1:61739_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1334409 INFO (jetty-launcher-5453-thread-2) [n:127.0.0.1:61743_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61743.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6b68e51
[junit4] 2> 1334409 INFO (jetty-launcher-5453-thread-2) [n:127.0.0.1:61743_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61743.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6b68e51
[junit4] 2> 1334411 INFO (jetty-launcher-5453-thread-2) [n:127.0.0.1:61743_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_438E29C06A18E64D-001\tempDir-001\node2\.
[junit4] 2> 1334428 INFO (jetty-launcher-5453-thread-3) [n:127.0.0.1:61739_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61739.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6b68e51
[junit4] 2> 1334443 INFO (jetty-launcher-5453-thread-3) [n:127.0.0.1:61739_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61739.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6b68e51
[junit4] 2> 1334443 INFO (jetty-launcher-5453-thread-3) [n:127.0.0.1:61739_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61739.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6b68e51
[junit4] 2> 1334446 INFO (jetty-launcher-5453-thread-3) [n:127.0.0.1:61739_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_438E29C06A18E64D-001\tempDir-001\node3\.
[junit4] 2> 1334506 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[438E29C06A18E64D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1334511 INFO (zkConnectionManagerCallback-5515-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1334511 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[438E29C06A18E64D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1334514 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[438E29C06A18E64D]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1334516 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[438E29C06A18E64D]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:61726/solr ready
[junit4] 2> 1334553 INFO (qtp200310866-15134) [n:127.0.0.1:61743_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=authCollection&nrtReplicas=1&action=CREATE&numShards=3&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1334561 INFO (OverseerThreadFactory-4917-thread-1-processing-n:127.0.0.1:61740_solr) [n:127.0.0.1:61740_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection authCollection
[junit4] 2> 1334709 INFO (OverseerStateUpdate-72068703706349578-127.0.0.1:61740_solr-n_0000000000) [n:127.0.0.1:61740_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"authCollection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"authCollection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:61743/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1334712 INFO (OverseerStateUpdate-72068703706349578-127.0.0.1:61740_solr-n_0000000000) [n:127.0.0.1:61740_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"authCollection",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"authCollection_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:61740/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1334716 INFO (OverseerStateUpdate-72068703706349578-127.0.0.1:61740_solr-n_0000000000) [n:127.0.0.1:61740_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"authCollection",
[junit4] 2> "shard":"shard3",
[junit4] 2> "core":"authCollection_shard3_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:61739/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1334968 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr x:authCollection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=authCollection_shard1_replica_n1&action=CREATE&numShards=3&collection=authCollection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1334985 INFO (qtp934164508-15139) [n:127.0.0.1:61739_solr x:authCollection_shard3_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=authCollection_shard3_replica_n4&action=CREATE&numShards=3&collection=authCollection&shard=shard3&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1334988 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr x:authCollection_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=authCollection_shard2_replica_n2&action=CREATE&numShards=3&collection=authCollection&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1334988 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr x:authCollection_shard2_replica_n2 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1336045 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1336056 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.s.IndexSchema [authCollection_shard1_replica_n1] Schema name=minimal
[junit4] 2> 1336058 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1336058 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1336058 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard1_replica_n1' using configuration from collection authCollection, trusted=true
[junit4] 2> 1336060 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61743.solr.core.authCollection.shard1.replica_n1' (registry 'solr.core.authCollection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6b68e51
[junit4] 2> 1336060 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.SolrCore [[authCollection_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_438E29C06A18E64D-001\tempDir-001\node2\authCollection_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_438E29C06A18E64D-001\tempDir-001\node2\.\authCollection_shard1_replica_n1\data\]
[junit4] 2> 1336070 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.s.IndexSchema [authCollection_shard2_replica_n2] Schema name=minimal
[junit4] 2> 1336092 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1336092 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard2_replica_n2' using configuration from collection authCollection, trusted=true
[junit4] 2> 1336094 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61740.solr.core.authCollection.shard2.replica_n2' (registry 'solr.core.authCollection.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6b68e51
[junit4] 2> 1336094 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.SolrCore [[authCollection_shard2_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_438E29C06A18E64D-001\tempDir-001\node1\authCollection_shard2_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_438E29C06A18E64D-001\tempDir-001\node1\.\authCollection_shard2_replica_n2\data\]
[junit4] 2> 1336115 INFO (qtp934164508-15139) [n:127.0.0.1:61739_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1336131 INFO (qtp934164508-15139) [n:127.0.0.1:61739_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.s.IndexSchema [authCollection_shard3_replica_n4] Schema name=minimal
[junit4] 2> 1336134 INFO (qtp934164508-15139) [n:127.0.0.1:61739_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1336134 INFO (qtp934164508-15139) [n:127.0.0.1:61739_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard3_replica_n4' using configuration from collection authCollection, trusted=true
[junit4] 2> 1336135 INFO (qtp934164508-15139) [n:127.0.0.1:61739_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61739.solr.core.authCollection.shard3.replica_n4' (registry 'solr.core.authCollection.shard3.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6b68e51
[junit4] 2> 1336135 INFO (qtp934164508-15139) [n:127.0.0.1:61739_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.c.SolrCore [[authCollection_shard3_replica_n4] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_438E29C06A18E64D-001\tempDir-001\node3\authCollection_shard3_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_438E29C06A18E64D-001\tempDir-001\node3\.\authCollection_shard3_replica_n4\data\]
[junit4] 2> 1336290 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1336290 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1336302 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1336302 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1336332 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1336332 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1336335 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1336335 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1336349 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@15aac9fd[authCollection_shard1_replica_n1] main]
[junit4] 2> 1336349 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2774e44b[authCollection_shard2_replica_n2] main]
[junit4] 2> 1336352 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1336352 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1336353 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1336353 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1336353 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1336353 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1336353 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1638869758283612160
[junit4] 2> 1336353 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1638869758283612160
[junit4] 2> 1336357 INFO (searcherExecutor-4930-thread-1-processing-n:127.0.0.1:61740_solr x:authCollection_shard2_replica_n2 c:authCollection s:shard2 r:core_node5) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.SolrCore [authCollection_shard2_replica_n2] Registered new searcher Searcher@2774e44b[authCollection_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1336359 INFO (searcherExecutor-4929-thread-1-processing-n:127.0.0.1:61743_solr x:authCollection_shard1_replica_n1 c:authCollection s:shard1 r:core_node3) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.SolrCore [authCollection_shard1_replica_n1] Registered new searcher Searcher@15aac9fd[authCollection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1336398 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/authCollection/terms/shard2 to Terms{values={core_node5=0}, version=0}
[junit4] 2> 1336398 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/authCollection/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 1336398 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/authCollection/leaders/shard2
[junit4] 2> 1336398 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/authCollection/leaders/shard1
[junit4] 2> 1336406 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1336406 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1336406 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:61740/solr/authCollection_shard2_replica_n2/
[junit4] 2> 1336406 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1336406 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1336406 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:61743/solr/authCollection_shard1_replica_n1/
[junit4] 2> 1336406 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1336406 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1336406 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.SyncStrategy https://127.0.0.1:61740/solr/authCollection_shard2_replica_n2/ has no replicas
[junit4] 2> 1336406 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/authCollection/leaders/shard2/leader after winning as /collections/authCollection/leader_elect/shard2/election/72068703706349578-core_node5-n_0000000000
[junit4] 2> 1336407 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:61743/solr/authCollection_shard1_replica_n1/ has no replicas
[junit4] 2> 1336407 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/authCollection/leaders/shard1/leader after winning as /collections/authCollection/leader_elect/shard1/election/72068703706349576-core_node3-n_0000000000
[junit4] 2> 1336410 INFO (qtp600659393-15149) [n:127.0.0.1:61740_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:61740/solr/authCollection_shard2_replica_n2/ shard2
[junit4] 2> 1336411 INFO (qtp200310866-15157) [n:127.0.0.1:61743_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:61743/solr/authCollection_shard1_replica_n1/ shard1
[junit4] 2> 1336495 INFO (qtp934164508-15139) [n:127.0.0.1:61739_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1336495 INFO (qtp934164508-15139) [n:127.0.0.1:61739_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1336499 INFO (qtp934164508-15139) [n:127.0.0.1:61739_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1336499 INFO (qtp934164508-15139) [n:127.0.0.1:61739_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1336499 INFO (qtp934164508-15139) [n:127.0.0.1:61739_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7395ba[authCollection_shard3_replica_n4] main]
[junit4] 2> 1336502 INFO (qtp934164508-15139) [n:127.0.0.1:61739_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1336502 INFO (qtp934164508-15139) [n:127.0.0.1:61739_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1336502 INFO (qtp934164508-15139) [n:127.0.0.1:61739_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1336502 INFO (qtp934164508-15139) [n:127.0.0.1:61739_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.u.UpdateLog Could not fin
[...truncated too long message...]
ions/authCollection/state.json
[junit4] 2> 9 /solr/clusterstate.json
[junit4] 2> 9 /solr/clusterprops.json
[junit4] 2> 3 /solr/overseer_elect/election/72069044143456266-127.0.0.1:54027_solr-n_0000000000
[junit4] 2> 3 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 29 /solr/live_nodes
[junit4] 2> 24 /solr/overseer/queue
[junit4] 2> 16 /solr/collections
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 59751 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[438E29C06A18E64D]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:54011
[junit4] 2> 59751 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[438E29C06A18E64D]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 54011
[junit4] 2> 62124 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[438E29C06A18E64D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testBasicAuth
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=BasicAuthIntegrationTest -Dtests.method=testBasicAuth -Dtests.seed=438E29C06A18E64D -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=sq-MK -Dtests.timezone=Asia/Ulaanbaatar -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[junit4] ERROR 7.99s J0 | BasicAuthIntegrationTest.testBasicAuth <<<
[junit4] > Throwable #1: javax.net.ssl.SSLException: Software caused connection abort: recv failed
[junit4] > at __randomizedtesting.SeedInfo.seed([438E29C06A18E64D:FFE05FD2CE4B6537]:0)
[junit4] > at java.base/sun.security.ssl.Alert.createSSLException(Alert.java:127)
[junit4] > at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:320)
[junit4] > at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:263)
[junit4] > at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:258)
[junit4] > at java.base/sun.security.ssl.SSLSocketImpl.handleException(SSLSocketImpl.java:1342)
[junit4] > at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:844)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
[junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
[junit4] > at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
[junit4] > at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
[junit4] > at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
[junit4] > at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
[junit4] > at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
[junit4] > at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
[junit4] > at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:108)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
[junit4] > at org.apache.solr.cloud.SolrCloudAuthTestCase.verifySecurityStatus(SolrCloudAuthTestCase.java:200)
[junit4] > at org.apache.solr.cloud.SolrCloudAuthTestCase.verifySecurityStatus(SolrCloudAuthTestCase.java:176)
[junit4] > at org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth(BasicAuthIntegrationTest.java:127)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:567)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:835)
[junit4] > Suppressed: java.net.SocketException: Software caused connection abort: socket write error
[junit4] > at java.base/java.net.SocketOutputStream.socketWrite0(Native Method)
[junit4] > at java.base/java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:110)
[junit4] > at java.base/java.net.SocketOutputStream.write(SocketOutputStream.java:150)
[junit4] > at java.base/sun.security.ssl.SSLSocketOutputRecord.encodeAlert(SSLSocketOutputRecord.java:81)
[junit4] > at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:351)
[junit4] > ... 63 more
[junit4] > Caused by: java.net.SocketException: Software caused connection abort: recv failed
[junit4] > at java.base/java.net.SocketInputStream.socketRead0(Native Method)
[junit4] > at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
[junit4] > at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
[junit4] > at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
[junit4] > at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:448)
[junit4] > at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68)
[junit4] > at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1132)
[junit4] > at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:828)
[junit4] > ... 59 more
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.BasicAuthIntegrationTest_438E29C06A18E64D-001
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@56edd6d3), locale=sq-MK, timezone=Asia/Ulaanbaatar
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 12.0.1 (64-bit)/cpus=3,threads=1,free=24717272,total=152436736
[junit4] 2> NOTE: All tests run in this JVM: [BasicAuthIntegrationTest, BasicAuthIntegrationTest]
[junit4] Completed [5/5 (4!)] on J0 in 8.15s, 1 test, 1 error <<< FAILURES!
[...truncated 13 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1492: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1019: There were test failures: 5 suites, 5 tests, 4 errors [seed: 438E29C06A18E64D]
Total time: 1 minute 5 seconds
[repro] Setting last failure code to 1
[repro] Failures:
[repro] 4/5 failed: org.apache.solr.security.BasicAuthIntegrationTest
[repro] Exiting with code 1
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST lucene\build move /y lucene\build lucene\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST solr\build move /y solr\build solr\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST lucene\build.orig move /y lucene\build.orig lucene\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST solr\build.orig move /y solr\build.orig solr\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>exit 0
Archiving artifacts
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=aab166d8305ef143506e57b57da9df37b1c7a2af, workspace=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows
[WARNINGS] Computing warning deltas based on reference build #8049
Recording test results
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2